builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1484718474.14 results: success (0) buildid: 20170117182427 builduid: ab47a86837d34e5f8201b75a8a04f7e8 revision: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.142922) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.143369) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.143658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.160216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.160477) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-17 21:47:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.9M=0s 2017-01-17 21:47:54 (69.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124644 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.298109) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.298394) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015004 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.348095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.348404) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-17 21:47:54,423 truncating revision to first 12 chars 2017-01-17 21:47:54,423 Setting DEBUG logging. 2017-01-17 21:47:54,423 attempt 1/10 2017-01-17 21:47:54,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fd66a1e49411?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-17 21:47:54,724 unpacking tar archive at: mozilla-aurora-fd66a1e49411/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636920 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:54.999220) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:55.001229) ========= script_repo_revision: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:55.001686) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:55.001973) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 21:47:55.011837) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-01-17 21:47:55.012194) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:47:55 INFO - MultiFileLogger online at 20170117 21:47:55 in /builds/slave/test 21:47:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:47:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:47:55 INFO - {'append_to_log': False, 21:47:55 INFO - 'base_work_dir': '/builds/slave/test', 21:47:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:47:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:47:55 INFO - 'branch': 'Mozilla-Aurora', 21:47:55 INFO - 'buildbot_json_path': 'buildprops.json', 21:47:55 INFO - 'config_files': ('talos/linux_config.py',), 21:47:55 INFO - 'default_actions': ('clobber', 21:47:55 INFO - 'read-buildbot-config', 21:47:55 INFO - 'download-and-extract', 21:47:55 INFO - 'populate-webroot', 21:47:55 INFO - 'create-virtualenv', 21:47:55 INFO - 'install', 21:47:55 INFO - 'run-tests'), 21:47:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:47:55 INFO - 'download_minidump_stackwalk': True, 21:47:55 INFO - 'download_symbols': 'ondemand', 21:47:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:47:55 INFO - 'tooltool.py': '/tools/tooltool.py', 21:47:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:47:55 INFO - '/tools/misc-python/virtualenv.py')}, 21:47:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:47:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:47:55 INFO - 'installer_path': 'installer.exe', 21:47:55 INFO - 'log_level': 'info', 21:47:55 INFO - 'log_name': 'talos', 21:47:55 INFO - 'log_to_console': True, 21:47:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:47:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:47:55 INFO - 'opt_config_files': (), 21:47:55 INFO - 'pip_index': False, 21:47:55 INFO - 'sps_profile': False, 21:47:55 INFO - 'sps_profile_interval': 0, 21:47:55 INFO - 'suite': 'dromaeojs', 21:47:55 INFO - 'system_bits': '32', 21:47:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:47:55 INFO - 'title': 'talos-linux64-ix-013', 21:47:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:47:55 INFO - 'use_talos_json': True, 21:47:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:47:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:47:55 INFO - 'work_dir': 'build'} 21:47:55 INFO - [mozharness: 2017-01-18 05:47:55.184402Z] Running clobber step. 21:47:55 INFO - Running pre-action listener: _resource_record_pre_action 21:47:55 INFO - Running main action method: clobber 21:47:55 INFO - rmtree: /builds/slave/test/build 21:47:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:47:55 INFO - Running post-action listener: _resource_record_post_action 21:47:55 INFO - [mozharness: 2017-01-18 05:47:55.641404Z] Finished clobber step (success) 21:47:55 INFO - [mozharness: 2017-01-18 05:47:55.641503Z] Running read-buildbot-config step. 21:47:55 INFO - Running pre-action listener: _resource_record_pre_action 21:47:55 INFO - Running main action method: read_buildbot_config 21:47:55 INFO - Using buildbot properties: 21:47:55 INFO - { 21:47:55 INFO - "project": "", 21:47:55 INFO - "product": "firefox", 21:47:55 INFO - "script_repo_revision": "production", 21:47:55 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:47:55 INFO - "repository": "", 21:47:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:47:55 INFO - "buildid": "20170117182427", 21:47:55 INFO - "pgo_build": "True", 21:47:55 INFO - "basedir": "/builds/slave/test", 21:47:55 INFO - "buildnumber": 712, 21:47:55 INFO - "slavename": "talos-linux64-ix-013", 21:47:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:47:55 INFO - "platform": "ubuntu64_hw", 21:47:55 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:47:55 INFO - "branch": "mozilla-aurora", 21:47:55 INFO - "repo_path": "releases/mozilla-aurora", 21:47:55 INFO - "revision": "fd66a1e49411faca500b26ba9ca7dd5a311ff6e5", 21:47:55 INFO - "stage_platform": "linux64-pgo", 21:47:55 INFO - "builduid": "ab47a86837d34e5f8201b75a8a04f7e8", 21:47:55 INFO - "slavebuilddir": "test" 21:47:55 INFO - } 21:47:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 21:47:55 INFO - Running post-action listener: _resource_record_post_action 21:47:55 INFO - [mozharness: 2017-01-18 05:47:55.643908Z] Finished read-buildbot-config step (success) 21:47:55 INFO - [mozharness: 2017-01-18 05:47:55.643996Z] Running download-and-extract step. 21:47:55 INFO - Running pre-action listener: _resource_record_pre_action 21:47:55 INFO - Running main action method: download_and_extract 21:47:55 INFO - mkdir: /builds/slave/test/build/tests 21:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:47:55 INFO - trying https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 21:47:55 INFO - Downloading https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 21:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:47:56 INFO - Downloaded 1361 bytes. 21:47:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 21:47:56 INFO - Using the following test package requirements: 21:47:56 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 21:47:56 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:47:56 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:47:56 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'jsshell-linux-x86_64.zip'], 21:47:56 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:47:56 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 21:47:56 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:47:56 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:47:56 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:47:56 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 21:47:56 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:47:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:47:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 21:47:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:47:56 INFO - Fetch https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 21:47:56 INFO - Http code: 200 21:47:56 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 21:47:56 INFO - x-amz-cf-id: 9xAY1l-t31aZfhe4tLnJGlPop-gUNieIPMcB0VCxQHZ9ajhOs_75hg== 21:47:56 INFO - x-amz-version-id: Plm3axttOFYRtBHT59Jj0TcVSc4lS4jO 21:47:57 INFO - Expected file size: 21947954 21:47:57 INFO - Obtained file size: 21947954 21:48:01 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:48:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 21:48:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:48:01 INFO - Fetch https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 21:48:02 INFO - Http code: 200 21:48:02 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 21:48:02 INFO - x-amz-cf-id: gqKvBjBFCIjrFiKskXbUg9kyOx7fcE_EMDQuOJIorKwQBK4uTZfnnA== 21:48:02 INFO - x-amz-version-id: Plm3axttOFYRtBHT59Jj0TcVSc4lS4jO 21:48:02 INFO - Expected file size: 21947954 21:48:02 INFO - Obtained file size: 21947954 21:48:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 21:48:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:48:06 INFO - Fetch https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 21:48:07 INFO - Http code: 200 21:48:07 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 21:48:07 INFO - x-amz-cf-id: OIRtnAqcPuzeOf31FRYd-2RaYsfhYgLvaT74mUNUlB1YIBpAqfP9Xw== 21:48:07 INFO - x-amz-version-id: XUAzjB8BjzCwofRJD2_RTJ4rNmkLCqTx 21:48:08 INFO - Expected file size: 14409038 21:48:08 INFO - Obtained file size: 14409038 21:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:08 INFO - trying https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 21:48:08 INFO - Downloading https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 21:48:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:48:11 INFO - Downloaded 64166962 bytes. 21:48:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 21:48:11 INFO - mkdir: /builds/slave/test/properties 21:48:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:48:11 INFO - Writing to file /builds/slave/test/properties/build_url 21:48:11 INFO - Contents: 21:48:11 INFO - build_url:https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 21:48:11 INFO - Running post-action listener: _resource_record_post_action 21:48:11 INFO - Running post-action listener: set_extra_try_arguments 21:48:11 INFO - [mozharness: 2017-01-18 05:48:11.923712Z] Finished download-and-extract step (success) 21:48:11 INFO - [mozharness: 2017-01-18 05:48:11.923940Z] Running populate-webroot step. 21:48:11 INFO - Running pre-action listener: _resource_record_pre_action 21:48:11 INFO - Running main action method: populate_webroot 21:48:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:48:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:48:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:48:11 INFO - '--tppagecycles', 21:48:11 INFO - '7'], 21:48:11 INFO - 'tests': ['tsvgm']}}, 21:48:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:48:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:48:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:48:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:48:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['damp', 'tps']}, 21:48:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'tests': ['damp', 'tps']}, 21:48:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['dromaeo_dom']}, 21:48:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:48:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['basic_compositor_video']}, 21:48:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:48:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['a11yr', 21:48:11 INFO - 'ts_paint', 21:48:11 INFO - 'tpaint', 21:48:11 INFO - 'sessionrestore', 21:48:11 INFO - 'sessionrestore_no_auto_restore', 21:48:11 INFO - 'tabpaint']}, 21:48:11 INFO - 'other-e10s': {'tests': ['a11yr', 21:48:11 INFO - 'ts_paint', 21:48:11 INFO - 'tpaint', 21:48:11 INFO - 'sessionrestore', 21:48:11 INFO - 'sessionrestore_no_auto_restore', 21:48:11 INFO - 'tabpaint']}, 21:48:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:48:11 INFO - 'ts_paint', 21:48:11 INFO - 'tpaint', 21:48:11 INFO - 'sessionrestore', 21:48:11 INFO - 'sessionrestore_no_auto_restore', 21:48:11 INFO - 'tabpaint']}, 21:48:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:48:11 INFO - 'ts_paint', 21:48:11 INFO - 'tpaint', 21:48:11 INFO - 'sessionrestore', 21:48:11 INFO - 'sessionrestore_no_auto_restore', 21:48:11 INFO - 'tabpaint']}, 21:48:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['a11yr', 21:48:11 INFO - 'ts_paint', 21:48:11 INFO - 'tpaint', 21:48:11 INFO - 'sessionrestore', 21:48:11 INFO - 'sessionrestore_no_auto_restore', 21:48:11 INFO - 'tabpaint']}, 21:48:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['a11yr', 21:48:11 INFO - 'ts_paint', 21:48:11 INFO - 'tpaint', 21:48:11 INFO - 'sessionrestore', 21:48:11 INFO - 'sessionrestore_no_auto_restore', 21:48:11 INFO - 'tabpaint']}, 21:48:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['tsvgx', 21:48:11 INFO - 'tsvgr_opacity', 21:48:11 INFO - 'tart', 21:48:11 INFO - 'tscrollx', 21:48:11 INFO - 'cart']}, 21:48:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:48:11 INFO - 'tsvgr_opacity', 21:48:11 INFO - 'tart', 21:48:11 INFO - 'tscrollx', 21:48:11 INFO - 'cart']}, 21:48:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'talos_options': ['--disable-e10s'], 21:48:11 INFO - 'tests': ['tp5o']}, 21:48:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'tests': ['tp5o']}, 21:48:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'talos_options': ['--disable-e10s', 21:48:11 INFO - '--xperf_path', 21:48:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:11 INFO - 'tests': ['tp5n']}, 21:48:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:11 INFO - 'talos_options': ['--xperf_path', 21:48:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:11 INFO - 'tests': ['tp5n']}}, 21:48:11 INFO - 'talos.zip': {'path': '', 21:48:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:48:11 INFO - Running post-action listener: _resource_record_post_action 21:48:11 INFO - [mozharness: 2017-01-18 05:48:11.943059Z] Finished populate-webroot step (success) 21:48:11 INFO - [mozharness: 2017-01-18 05:48:11.943146Z] Running create-virtualenv step. 21:48:11 INFO - Running pre-action listener: _resource_record_pre_action 21:48:11 INFO - Running main action method: create_virtualenv 21:48:11 INFO - Creating virtualenv /builds/slave/test/build/venv 21:48:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:48:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:48:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:48:12 INFO - Using real prefix '/usr' 21:48:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:48:12 INFO - Installing distribute.............................................................................................................................................................................................done. 21:48:14 INFO - Installing pip.................done. 21:48:14 INFO - Return code: 0 21:48:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:48:14 INFO - Reading from file tmpfile_stdout 21:48:14 INFO - Output received: 21:48:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:48:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:48:14 INFO - Using env: {'DISPLAY': ':0', 21:48:14 INFO - 'HOME': '/home/cltbld', 21:48:14 INFO - 'LANG': 'en_US.UTF-8', 21:48:14 INFO - 'LANGUAGE': 'en_US:en', 21:48:14 INFO - 'LOGNAME': 'cltbld', 21:48:14 INFO - 'MAIL': '/var/mail/cltbld', 21:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:14 INFO - 'MOZ_NO_REMOTE': '1', 21:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:14 INFO - 'NO_EM_RESTART': '1', 21:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:14 INFO - 'PWD': '/builds/slave/test', 21:48:14 INFO - 'SHELL': '/bin/bash', 21:48:14 INFO - 'SHLVL': '1', 21:48:14 INFO - 'TERM': 'linux', 21:48:14 INFO - 'TMOUT': '86400', 21:48:14 INFO - 'USER': 'cltbld', 21:48:14 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:14 INFO - '_': '/tools/buildbot/bin/python'} 21:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:48:14 INFO - Cleaning up... 21:48:14 INFO - Return code: 0 21:48:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:48:14 INFO - Using env: {'DISPLAY': ':0', 21:48:14 INFO - 'HOME': '/home/cltbld', 21:48:14 INFO - 'LANG': 'en_US.UTF-8', 21:48:14 INFO - 'LANGUAGE': 'en_US:en', 21:48:15 INFO - 'LOGNAME': 'cltbld', 21:48:15 INFO - 'MAIL': '/var/mail/cltbld', 21:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:15 INFO - 'MOZ_NO_REMOTE': '1', 21:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:15 INFO - 'NO_EM_RESTART': '1', 21:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:15 INFO - 'PWD': '/builds/slave/test', 21:48:15 INFO - 'SHELL': '/bin/bash', 21:48:15 INFO - 'SHLVL': '1', 21:48:15 INFO - 'TERM': 'linux', 21:48:15 INFO - 'TMOUT': '86400', 21:48:15 INFO - 'USER': 'cltbld', 21:48:15 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:15 INFO - '_': '/tools/buildbot/bin/python'} 21:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:15 INFO - Downloading/unpacking psutil>=3.1.1 21:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:17 INFO - Installing collected packages: psutil 21:48:17 INFO - Running setup.py install for psutil 21:48:17 INFO - building 'psutil._psutil_linux' extension 21:48:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:48:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:48:17 INFO - building 'psutil._psutil_posix' extension 21:48:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:48:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:17 INFO - Successfully installed psutil 21:48:17 INFO - Cleaning up... 21:48:17 INFO - Return code: 0 21:48:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:48:17 INFO - Using env: {'DISPLAY': ':0', 21:48:17 INFO - 'HOME': '/home/cltbld', 21:48:17 INFO - 'LANG': 'en_US.UTF-8', 21:48:17 INFO - 'LANGUAGE': 'en_US:en', 21:48:17 INFO - 'LOGNAME': 'cltbld', 21:48:17 INFO - 'MAIL': '/var/mail/cltbld', 21:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:17 INFO - 'MOZ_NO_REMOTE': '1', 21:48:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:17 INFO - 'NO_EM_RESTART': '1', 21:48:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:17 INFO - 'PWD': '/builds/slave/test', 21:48:17 INFO - 'SHELL': '/bin/bash', 21:48:17 INFO - 'SHLVL': '1', 21:48:17 INFO - 'TERM': 'linux', 21:48:17 INFO - 'TMOUT': '86400', 21:48:17 INFO - 'USER': 'cltbld', 21:48:17 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:17 INFO - '_': '/tools/buildbot/bin/python'} 21:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:48:20 INFO - Installing collected packages: mozsystemmonitor 21:48:20 INFO - Running setup.py install for mozsystemmonitor 21:48:20 INFO - Successfully installed mozsystemmonitor 21:48:20 INFO - Cleaning up... 21:48:20 INFO - Return code: 0 21:48:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:48:20 INFO - Using env: {'DISPLAY': ':0', 21:48:20 INFO - 'HOME': '/home/cltbld', 21:48:20 INFO - 'LANG': 'en_US.UTF-8', 21:48:20 INFO - 'LANGUAGE': 'en_US:en', 21:48:20 INFO - 'LOGNAME': 'cltbld', 21:48:20 INFO - 'MAIL': '/var/mail/cltbld', 21:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:20 INFO - 'MOZ_NO_REMOTE': '1', 21:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:20 INFO - 'NO_EM_RESTART': '1', 21:48:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:20 INFO - 'PWD': '/builds/slave/test', 21:48:20 INFO - 'SHELL': '/bin/bash', 21:48:20 INFO - 'SHLVL': '1', 21:48:20 INFO - 'TERM': 'linux', 21:48:20 INFO - 'TMOUT': '86400', 21:48:20 INFO - 'USER': 'cltbld', 21:48:20 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:20 INFO - '_': '/tools/buildbot/bin/python'} 21:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:20 INFO - Downloading/unpacking jsonschema==2.5.1 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:22 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:22 INFO - warning: no files found matching '*.txt' 21:48:22 INFO - no previously-included directories found matching 'build' 21:48:22 INFO - no previously-included directories found matching 'dist' 21:48:22 INFO - no previously-included directories found matching '.git*' 21:48:22 INFO - Installing collected packages: jsonschema, functools32 21:48:22 INFO - Running setup.py install for functools32 21:48:22 INFO - warning: no files found matching '*.txt' 21:48:22 INFO - no previously-included directories found matching 'build' 21:48:22 INFO - no previously-included directories found matching 'dist' 21:48:22 INFO - no previously-included directories found matching '.git*' 21:48:22 INFO - Successfully installed jsonschema functools32 21:48:22 INFO - Cleaning up... 21:48:23 INFO - Return code: 0 21:48:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:48:23 INFO - Using env: {'DISPLAY': ':0', 21:48:23 INFO - 'HOME': '/home/cltbld', 21:48:23 INFO - 'LANG': 'en_US.UTF-8', 21:48:23 INFO - 'LANGUAGE': 'en_US:en', 21:48:23 INFO - 'LOGNAME': 'cltbld', 21:48:23 INFO - 'MAIL': '/var/mail/cltbld', 21:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:23 INFO - 'MOZ_NO_REMOTE': '1', 21:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:23 INFO - 'NO_EM_RESTART': '1', 21:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:23 INFO - 'PWD': '/builds/slave/test', 21:48:23 INFO - 'SHELL': '/bin/bash', 21:48:23 INFO - 'SHLVL': '1', 21:48:23 INFO - 'TERM': 'linux', 21:48:23 INFO - 'TMOUT': '86400', 21:48:23 INFO - 'USER': 'cltbld', 21:48:23 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:23 INFO - '_': '/tools/buildbot/bin/python'} 21:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:48:23 INFO - Cleaning up... 21:48:23 INFO - Return code: 0 21:48:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:48:23 INFO - Using env: {'DISPLAY': ':0', 21:48:23 INFO - 'HOME': '/home/cltbld', 21:48:23 INFO - 'LANG': 'en_US.UTF-8', 21:48:23 INFO - 'LANGUAGE': 'en_US:en', 21:48:23 INFO - 'LOGNAME': 'cltbld', 21:48:23 INFO - 'MAIL': '/var/mail/cltbld', 21:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:23 INFO - 'MOZ_NO_REMOTE': '1', 21:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:23 INFO - 'NO_EM_RESTART': '1', 21:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:23 INFO - 'PWD': '/builds/slave/test', 21:48:23 INFO - 'SHELL': '/bin/bash', 21:48:23 INFO - 'SHLVL': '1', 21:48:23 INFO - 'TERM': 'linux', 21:48:23 INFO - 'TMOUT': '86400', 21:48:23 INFO - 'USER': 'cltbld', 21:48:23 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:23 INFO - '_': '/tools/buildbot/bin/python'} 21:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:23 INFO - Downloading/unpacking blobuploader==1.2.4 21:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:25 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:26 INFO - Downloading docopt-0.6.1.tar.gz 21:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:26 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:26 INFO - Running setup.py install for blobuploader 21:48:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:26 INFO - Running setup.py install for requests 21:48:26 INFO - Running setup.py install for docopt 21:48:26 INFO - Successfully installed blobuploader requests docopt 21:48:26 INFO - Cleaning up... 21:48:26 INFO - Return code: 0 21:48:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:48:26 INFO - Using env: {'DISPLAY': ':0', 21:48:26 INFO - 'HOME': '/home/cltbld', 21:48:26 INFO - 'LANG': 'en_US.UTF-8', 21:48:26 INFO - 'LANGUAGE': 'en_US:en', 21:48:26 INFO - 'LOGNAME': 'cltbld', 21:48:26 INFO - 'MAIL': '/var/mail/cltbld', 21:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:26 INFO - 'MOZ_NO_REMOTE': '1', 21:48:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:26 INFO - 'NO_EM_RESTART': '1', 21:48:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:26 INFO - 'PWD': '/builds/slave/test', 21:48:26 INFO - 'SHELL': '/bin/bash', 21:48:26 INFO - 'SHLVL': '1', 21:48:26 INFO - 'TERM': 'linux', 21:48:26 INFO - 'TMOUT': '86400', 21:48:26 INFO - 'USER': 'cltbld', 21:48:26 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:26 INFO - '_': '/tools/buildbot/bin/python'} 21:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:27 INFO - Running setup.py (path:/tmp/pip-l4512i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:27 INFO - Running setup.py (path:/tmp/pip-6FKnoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:27 INFO - Running setup.py (path:/tmp/pip-GvFg0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:27 INFO - Running setup.py (path:/tmp/pip-CDng2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:27 INFO - Running setup.py (path:/tmp/pip-SgUjvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:27 INFO - Running setup.py (path:/tmp/pip-s_F7C2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:27 INFO - Running setup.py (path:/tmp/pip-LX0IzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:27 INFO - Running setup.py (path:/tmp/pip-WzT92B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:27 INFO - Running setup.py (path:/tmp/pip-oBmh4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:27 INFO - Running setup.py (path:/tmp/pip-9MqTSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:27 INFO - Running setup.py (path:/tmp/pip-ljibgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:27 INFO - Running setup.py (path:/tmp/pip-bz7CgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:27 INFO - Running setup.py (path:/tmp/pip-3CYUk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:28 INFO - Running setup.py (path:/tmp/pip-NHbtIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:28 INFO - Running setup.py (path:/tmp/pip-mhhPf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:28 INFO - Running setup.py (path:/tmp/pip-ZNd3Fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:28 INFO - Running setup.py (path:/tmp/pip-K1xsPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:28 INFO - Running setup.py install for manifestparser 21:48:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:28 INFO - Running setup.py install for mozcrash 21:48:28 INFO - Running setup.py install for mozdebug 21:48:28 INFO - Running setup.py install for mozdevice 21:48:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:28 INFO - Running setup.py install for mozfile 21:48:28 INFO - Running setup.py install for mozhttpd 21:48:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:28 INFO - Running setup.py install for mozinfo 21:48:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Running setup.py install for mozInstall 21:48:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Running setup.py install for mozleak 21:48:29 INFO - Running setup.py install for mozlog 21:48:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Running setup.py install for moznetwork 21:48:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Running setup.py install for mozprocess 21:48:29 INFO - Running setup.py install for mozprofile 21:48:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Running setup.py install for mozrunner 21:48:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:29 INFO - Running setup.py install for mozscreenshot 21:48:29 INFO - Running setup.py install for moztest 21:48:30 INFO - Running setup.py install for mozversion 21:48:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:48:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:48:30 INFO - Cleaning up... 21:48:30 INFO - Return code: 0 21:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:48:30 INFO - Using env: {'DISPLAY': ':0', 21:48:30 INFO - 'HOME': '/home/cltbld', 21:48:30 INFO - 'LANG': 'en_US.UTF-8', 21:48:30 INFO - 'LANGUAGE': 'en_US:en', 21:48:30 INFO - 'LOGNAME': 'cltbld', 21:48:30 INFO - 'MAIL': '/var/mail/cltbld', 21:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:30 INFO - 'MOZ_NO_REMOTE': '1', 21:48:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:30 INFO - 'NO_EM_RESTART': '1', 21:48:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:30 INFO - 'PWD': '/builds/slave/test', 21:48:30 INFO - 'SHELL': '/bin/bash', 21:48:30 INFO - 'SHLVL': '1', 21:48:30 INFO - 'TERM': 'linux', 21:48:30 INFO - 'TMOUT': '86400', 21:48:30 INFO - 'USER': 'cltbld', 21:48:30 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:30 INFO - '_': '/tools/buildbot/bin/python'} 21:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:30 INFO - Running setup.py (path:/tmp/pip-G3rXhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:30 INFO - Running setup.py (path:/tmp/pip-h_1Kfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:30 INFO - Running setup.py (path:/tmp/pip-I1EhSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:30 INFO - Running setup.py (path:/tmp/pip-eb6bRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:30 INFO - Running setup.py (path:/tmp/pip-alrDI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:30 INFO - Running setup.py (path:/tmp/pip-Nq_w6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:30 INFO - Running setup.py (path:/tmp/pip-FSljJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:30 INFO - Running setup.py (path:/tmp/pip-Dago9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:30 INFO - Running setup.py (path:/tmp/pip-iel01x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:31 INFO - Running setup.py (path:/tmp/pip-rdze5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:31 INFO - Running setup.py (path:/tmp/pip-h8uKJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:31 INFO - Running setup.py (path:/tmp/pip-qJCfJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:31 INFO - Running setup.py (path:/tmp/pip-11B31y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:31 INFO - Running setup.py (path:/tmp/pip-AjVpFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:31 INFO - Running setup.py (path:/tmp/pip-jIlI6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:31 INFO - Running setup.py (path:/tmp/pip-kxCGNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:31 INFO - Running setup.py (path:/tmp/pip-Z_Bhxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:33 INFO - Downloading blessings-1.6.tar.gz 21:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:48:33 INFO - Installing collected packages: blessings 21:48:33 INFO - Running setup.py install for blessings 21:48:33 INFO - Successfully installed blessings 21:48:33 INFO - Cleaning up... 21:48:33 INFO - Return code: 0 21:48:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:48:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:34 INFO - Reading from file tmpfile_stdout 21:48:34 INFO - Current package versions: 21:48:34 INFO - argparse == 1.2.1 21:48:34 INFO - blessings == 1.6 21:48:34 INFO - blobuploader == 1.2.4 21:48:34 INFO - docopt == 0.6.1 21:48:34 INFO - functools32 == 3.2.3-2 21:48:34 INFO - jsonschema == 2.5.1 21:48:34 INFO - manifestparser == 1.1 21:48:34 INFO - mozInstall == 1.12 21:48:34 INFO - mozcrash == 1.0 21:48:34 INFO - mozdebug == 0.1 21:48:34 INFO - mozdevice == 0.48 21:48:34 INFO - mozfile == 1.2 21:48:34 INFO - mozhttpd == 0.7 21:48:34 INFO - mozinfo == 0.9 21:48:34 INFO - mozleak == 0.1 21:48:34 INFO - mozlog == 3.4 21:48:34 INFO - moznetwork == 0.27 21:48:34 INFO - mozprocess == 0.23 21:48:34 INFO - mozprofile == 0.28 21:48:34 INFO - mozrunner == 6.13 21:48:34 INFO - mozscreenshot == 0.1 21:48:34 INFO - mozsystemmonitor == 0.3 21:48:34 INFO - moztest == 0.8 21:48:34 INFO - mozversion == 1.4 21:48:34 INFO - psutil == 3.1.1 21:48:34 INFO - requests == 1.2.3 21:48:34 INFO - wsgiref == 0.1.2 21:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:48:34 INFO - Using env: {'DISPLAY': ':0', 21:48:34 INFO - 'HOME': '/home/cltbld', 21:48:34 INFO - 'LANG': 'en_US.UTF-8', 21:48:34 INFO - 'LANGUAGE': 'en_US:en', 21:48:34 INFO - 'LOGNAME': 'cltbld', 21:48:34 INFO - 'MAIL': '/var/mail/cltbld', 21:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:34 INFO - 'MOZ_NO_REMOTE': '1', 21:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:34 INFO - 'NO_EM_RESTART': '1', 21:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:34 INFO - 'PWD': '/builds/slave/test', 21:48:34 INFO - 'SHELL': '/bin/bash', 21:48:34 INFO - 'SHLVL': '1', 21:48:34 INFO - 'TERM': 'linux', 21:48:34 INFO - 'TMOUT': '86400', 21:48:34 INFO - 'USER': 'cltbld', 21:48:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:34 INFO - '_': '/tools/buildbot/bin/python'} 21:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:48:34 INFO - Cleaning up... 21:48:34 INFO - Return code: 0 21:48:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3ed591f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x130f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13dcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:48:34 INFO - Using env: {'DISPLAY': ':0', 21:48:34 INFO - 'HOME': '/home/cltbld', 21:48:34 INFO - 'LANG': 'en_US.UTF-8', 21:48:34 INFO - 'LANGUAGE': 'en_US:en', 21:48:34 INFO - 'LOGNAME': 'cltbld', 21:48:34 INFO - 'MAIL': '/var/mail/cltbld', 21:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:34 INFO - 'MOZ_NO_REMOTE': '1', 21:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:34 INFO - 'NO_EM_RESTART': '1', 21:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:34 INFO - 'PWD': '/builds/slave/test', 21:48:34 INFO - 'SHELL': '/bin/bash', 21:48:34 INFO - 'SHLVL': '1', 21:48:34 INFO - 'TERM': 'linux', 21:48:34 INFO - 'TMOUT': '86400', 21:48:34 INFO - 'USER': 'cltbld', 21:48:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:34 INFO - '_': '/tools/buildbot/bin/python'} 21:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:48:34 INFO - Cleaning up... 21:48:34 INFO - Return code: 0 21:48:34 INFO - Running post-action listener: _resource_record_post_action 21:48:34 INFO - Running post-action listener: _start_resource_monitoring 21:48:34 INFO - Starting resource monitoring. 21:48:34 INFO - [mozharness: 2017-01-18 05:48:34.570449Z] Finished create-virtualenv step (success) 21:48:34 INFO - [mozharness: 2017-01-18 05:48:34.570955Z] Running install step. 21:48:34 INFO - Running pre-action listener: _resource_record_pre_action 21:48:34 INFO - Running main action method: install 21:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:34 INFO - Reading from file tmpfile_stdout 21:48:34 INFO - Detecting whether we're running mozinstall >=1.0... 21:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:48:34 INFO - Reading from file tmpfile_stdout 21:48:34 INFO - Output received: 21:48:34 INFO - Usage: mozinstall [options] installer 21:48:34 INFO - Options: 21:48:34 INFO - -h, --help show this help message and exit 21:48:34 INFO - -d DEST, --destination=DEST 21:48:34 INFO - Directory to install application into. [default: 21:48:34 INFO - "/builds/slave/test"] 21:48:34 INFO - --app=APP Application being installed. [default: firefox] 21:48:34 INFO - mkdir: /builds/slave/test/build/application 21:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:48:51 INFO - Reading from file tmpfile_stdout 21:48:51 INFO - Output received: 21:48:51 INFO - /builds/slave/test/build/application/firefox/firefox 21:48:51 INFO - Running post-action listener: _resource_record_post_action 21:48:51 INFO - [mozharness: 2017-01-18 05:48:51.359345Z] Finished install step (success) 21:48:51 INFO - [mozharness: 2017-01-18 05:48:51.359587Z] Running run-tests step. 21:48:51 INFO - Running pre-action listener: _resource_record_pre_action 21:48:51 INFO - Running main action method: run_tests 21:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:48:51 INFO - Python 2.7.3 21:48:51 INFO - Return code: 0 21:48:51 INFO - grabbing minidump binary from tooltool 21:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d9f60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13dad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13db1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:48:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:48:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:48:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:48:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:48:51 INFO - Return code: 0 21:48:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:48:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:48:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:48:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:48:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:48:51 INFO - Using env: {'DISPLAY': ':0', 21:48:51 INFO - 'HOME': '/home/cltbld', 21:48:51 INFO - 'LANG': 'en_US.UTF-8', 21:48:51 INFO - 'LANGUAGE': 'en_US:en', 21:48:51 INFO - 'LOGNAME': 'cltbld', 21:48:51 INFO - 'MAIL': '/var/mail/cltbld', 21:48:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:48:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:51 INFO - 'MOZ_NO_REMOTE': '1', 21:48:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:48:51 INFO - 'NO_EM_RESTART': '1', 21:48:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:51 INFO - 'PWD': '/builds/slave/test', 21:48:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:48:51 INFO - 'SHELL': '/bin/bash', 21:48:51 INFO - 'SHLVL': '1', 21:48:51 INFO - 'TERM': 'linux', 21:48:51 INFO - 'TMOUT': '86400', 21:48:51 INFO - 'USER': 'cltbld', 21:48:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518', 21:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:51 INFO - '_': '/tools/buildbot/bin/python'} 21:48:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:48:51 INFO - mozversion application_buildid: 20170117182427 21:48:51 INFO - mozversion application_changeset: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 21:48:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:48:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:48:51 INFO - mozversion application_name: Firefox 21:48:51 INFO - mozversion application_remotingname: firefox-dev 21:48:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:48:51 INFO - mozversion application_vendor: Mozilla 21:48:51 INFO - mozversion application_version: 52.0a2 21:48:51 INFO - mozversion platform_buildid: 20170117182427 21:48:51 INFO - mozversion platform_changeset: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 21:48:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:48:51 INFO - mozversion platform_version: 52.0a2 21:48:51 INFO - using testdate: 1484718531 21:48:51 INFO - actual date: 1484718531 21:48:51 INFO - starting webserver on 'localhost:42971' 21:48:51 INFO - SUITE-START | Running 2 tests 21:48:51 INFO - TEST-START | dromaeo_css 21:48:51 INFO - operating with platform_type : linux_ 21:48:51 INFO - Initialising browser for dromaeo_css test... 21:48:51 INFO - TEST-INFO | started process 31519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLWgccu/profile http://localhost:42971/getInfo.html) 21:48:55 INFO - PROCESS | 31519 | __metrics Screen width/height:1600/1200 21:48:55 INFO - PROCESS | 31519 | colorDepth:24 21:48:55 INFO - PROCESS | 31519 | Browser inner width/height: 1024/697 21:48:55 INFO - PROCESS | 31519 | __metrics 21:48:56 INFO - PROCESS | 31519 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:48:56 INFO - PROCESS | 31519 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:48:56 INFO - PROCESS | 31519 | console.error: PushService: 21:48:56 INFO - PROCESS | 31519 | stateChangeProcessEnqueue: Error transitioning state 21:48:56 INFO - PROCESS | 31519 | UnknownError 21:48:57 INFO - TEST-INFO | 31519: exit 0 21:48:57 INFO - Browser initialized. 21:48:57 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:57 INFO - TEST-INFO | started process 31610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLWgccu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:09 INFO - PROCESS | 31610 | RSS: Main: 143908864 21:49:09 INFO - PROCESS | 31610 | 21:52:11 INFO - PROCESS | 31610 | Cycle 1(1): loaded http://localhost:42971/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42971/tests/dromaeo/cssquery-ext.html) 21:52:12 INFO - PROCESS | 31610 | RSS: Main: 243666944 21:52:12 INFO - PROCESS | 31610 | 21:55:14 INFO - PROCESS | 31610 | Cycle 1(1): loaded http://localhost:42971/tests/dromaeo/cssquery-ext.html (next: http://localhost:42971/tests/dromaeo/cssquery-jquery.html) 21:55:15 INFO - PROCESS | 31610 | RSS: Main: 233394176 21:55:15 INFO - PROCESS | 31610 | 21:58:17 INFO - PROCESS | 31610 | Cycle 1(1): loaded http://localhost:42971/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42971/tests/dromaeo/cssquery-mootools.html) 21:58:17 INFO - PROCESS | 31610 | RSS: Main: 176570368 21:58:17 INFO - PROCESS | 31610 | 22:01:19 INFO - PROCESS | 31610 | Cycle 1(1): loaded http://localhost:42971/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42971/tests/dromaeo/cssquery-prototype.html) 22:01:20 INFO - PROCESS | 31610 | RSS: Main: 216268800 22:01:20 INFO - PROCESS | 31610 | 22:04:23 INFO - PROCESS | 31610 | Cycle 1(1): loaded http://localhost:42971/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42971/tests/dromaeo/cssquery-yui.html) 22:04:23 INFO - PROCESS | 31610 | RSS: Main: 174080000 22:04:23 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | Cycle 1(1): loaded http://localhost:42971/tests/dromaeo/cssquery-yui.html (next: http://localhost:42971/tests/dromaeo/cssquery-dojo.html) 22:07:26 INFO - PROCESS | 31610 | RSS: Main: 262127616 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | __start_tp_report 22:07:26 INFO - PROCESS | 31610 | _x_x_mozilla_page_load 22:07:26 INFO - PROCESS | 31610 | _x_x_mozilla_page_load_details 22:07:26 INFO - PROCESS | 31610 | |i|pagename|runs| 22:07:26 INFO - PROCESS | 31610 | |0;dojo.html;3269.1009972257634;3273.1162586101755;3308.966910330896;3333.633300336961;3338.13208565773;5685.6020078594465;5751.539876809856;5763.250777398937;5764.5676574256995;5775.682337471438;5739.626924249931;5796.594238403313;5797.71011449428;5798.768049278035;5802.419758024195;5229.921551176735;5232.686038837663;5238.92141617875;5259.684418934857;5302.1781623848365;5057.898842023157;5293.814716484924;5299.496547827969;5312.6281160318795;5340.786368545254;5042.4201216860165;5155.097857874875;5168.689878607277;5185.351831021122;5232.058229518685;5135.768890399941;5174.379074511064;5176.301199338079;5260.973695131538;5271.130263506517;5138.6916784992645;5150.330457040598;5214.791408343662;5222.033923724085;5248.317718696583;5361.98122356751;5432.538234250093;5536.114221724505;5575.219469274305;5612.69130197839;6080.391960803929;6101;6102.847428814271;6158.56890017699;6185.907211391833;6190.380961903774;6190.659513726743;6203.069539569055;6207.658578778165;6233.719482623293;5441.809536666197;5491.258680078178;5623.465770751854;5755.424457554211;5769.9711501442225;6230.532710046765;6249.281332646713;6280.466160376419;6283.120363149163;6290.811275661646;6001.759929602855;6011.549133814981;6028.0355143176885;6036.261534695243;6067.302260240041;5871.060630299217;5930.8813823723285;6097.390260973956;6115.666295175888;6132.78535251264;6157.6613286269685;6165.599236049642;6166.7224974876235;6181.660008699565;6182.814515564541;6101.542832930421;6148.6925653717135;6157.230346206724;6166.5991710539565;6186.845328866814;4527.0493196429115;4586.908261834745;4638.234691275901;4698.718076915396;4782.52174782519;4175.895602609899;4189.664826813848;4192.643625291823;4198.517170525429;4206.284931561642;5791.247137872049;5799.536037117021;5800.187973683687;5802.158686990447;5803.187553742479;5835.241418615553;5905.76376944926;6070.028795392716;6167.444929956326;6170.938290617037;5742.511886489695;5782.768689252467;5793.536517078624;5809.622374545641;5822.272215973004;5195.948040519622;5196.506331898464;5202.453742356998;5207.895842083137;5211.140161873249;6127.0062805303205;6185.505159587132;6344.8413789654705;6386.042093685985;6394.648294343763;6008.8798224036145;6117.938820611737;6136.969315153574;6181.258249010148;6188.412100850412;6410.134631824644;6417.550771445954;6421.7110230038825;6423.64669943139;6424.100625912282;5701.600887937804;6051.667158306335;6053.334133245251;6053.667048312385;6055.394460553909;5915.171875937458;6068.51451883857;6075.483583895241;6078.54410919174;6160.784372546939;5930.199423078002;5987.161797348288;5988.041913294026;5989.40105989415;6012.308584512837;5977.731002104829;5991.071383935497;5992.64044157352;5992.8801423972145;5995.970020149872;5671;5794.391588883106;5851.327097383855;5875.794347197828;5890.882182356415;6026.578139530191;6040.75836966534;6043.607165534227;6046.939530604637;6062.42406971337;5634.352049514195;5686.516646085129;5879.147523609138;5883.0293001654245;5888.852778680567 22:07:26 INFO - PROCESS | 31610 | |1;ext.html;584.666739958224;616.740968793107;643.4916416031333;657.0997733105646;671.6775947545175;34432.31135377291;45684.08631827366;46836.5316346836;46872;46969.76515117419;46855.29717054247;47359.526404735945;47374.76312618432;47387;47424.762876185654;8263.380246481507;8795.956020219906;8949.77625559363;8952.8657070144;8956.641734330618;8164.061132969696;10552.314099583502;10772.784544309108;10774.515146818412;11124.833127503094;10785.83821242682;10843.457827108652;10851.457427128651;10857.619983300587;10864.837027444595;10701.625443109493;10772;10772.353658780497;10788.08301294383;10794.352338859655;10658.253922225447;10705.25063245573;10716.41059741714;10730.302530335503;10732.141428685687;44477.77761111206;44806.77596612029;44806.77596612029;44810.77594612006;44839;3563.1270338767167;3581.1584277694983;3628.782273063598;3884.3408796190924;4158.189153115144;32779.83610081958;32913.506297405555;33132.17169570756;33228.00315990524;34658.48012279818;4257.74453532789;4328.675349348811;4357.477102747659;4372.781360931973;4412.823487060546;265513;267737;270590;271289;272388;39040.2191956165;39787.60212397897;63537.36462635407;63756.681216594545;63947;40979.590204098175;41140;41153;41221.1755764889;41223.38164927528;15661.373545058297;15708.68582628341;15717.056976581212;15733;15748.92125539365;12703.68240793969;12976.67558311031;12985.805212921814;13006.284654343912;13204.141730787467;9312.48781317034;9403.57683904226;9414.2468602513;9505.66730164441;9522.047795220422;3544.929101417957;3545.503629491873;3598.676119149237;3644.671979521856;3645.4714066460215;978.295627148452;996.4419924842111;1021.7343490692474;1036.6838114375037;1085.2565992295144;31397.52903706446;33268;33455;33464.657473976586;33568.49647255293;1082.653550863716;1115.481301194932;1151.5048529132387;1204.0728638948135;1210.5883999440232;16048.27782749779;16358.509244722678;16551.586210344838;16737.748933766004;16755.497335079966;570103;573218;575752;576194;576867;59909.70045149746;60389;60433.39566604366;60515;60663.69668151719;1289.548657969703;1327.1307293722434;1373.6771858613217;1437;1449.253634378296;29603.703962960095;33245.83377083099;33588.66411335855;33795.66204338024;34046.148846279044;50411.74794126006;50854.237186441496;50992.745036276065;51018.74490627523;51047;426479;428179;428544;429425;430239;29937.85031074831;30673.846630766704;30941.69058309388;32276;32348.83825580951;49613.75193124155;49811.75094124506;50129.248061278384;50634.74682626563;51021.48978510167;41524.79237603793;41646.79176604097;41692.166156677304;42005.579944200166;43095.78452107719;42001.369979450945;42160.36759448672;42273.15453690848;43122.137557249305;43409.78295108504;41867.7906610465;42520.36219456772;42702.57297426986;42808;43060.138797223255;35498.29003419969;36657.63342366649;37385.252294954495;38588.80705596454;38614.613853862225;27267.181984540497;28890.855545722978;28950.42099158047;29166.56250156292;29331.560026599192 22:07:26 INFO - PROCESS | 31610 | |2;jquery.html;393.1354782488142;411.9032027473543;426.19661937248287;426.71623370458644;447.16380368710514;15001.924990375046;19022.858628482285;20513.282035128774;20531.38405847827;20677.483062923435;19852.305169319076;20760.377188684324;20810.271640492585;20817.16731330745;20953.16187352504;5696.800611978571;5883.23517942669;5890.145928840316;5894.793682221123;5896.734646940898;5605.943940560583;5783.334916484616;5793.13103034544;5796.275465566804;5802.738876750535;5399.676019438826;5743;5760.164776107483;5764.452377024176;5766.826995190151;5670.291213598301;5764.711764411804;5770.451807078321;5772.220750198711;5773.971130144343;5066.721330326831;5224.4775522447835;5242.501962313574;5250.369955605313;5250.9474905250845;23920.52158956811;24183;24192.2742317729;24204.878975605185;24284.63573046406;2437.9878100609385;2472.4560596668703;2476.4923190746035;2554.489102179553;2967.658719247271;14660.066532539238;15221.695566088727;15471.381144754196;15673;15953.282102305307;1863.5341164708823;1928.6142771445766;1930.4787707319085;1934.7291379206922;1965.4987978065644;85088;88002.99999999936;89628;90810.09189908083;92326;10066.194704423628;11120.777584448266;11202.159838012181;11327.773444531063;11368.488418021208;14127.15237085778;14921.925390372979;14961.326740296712;14961.925190374197;14992.550223493312;8398.286145677686;8535.701250456204;8611.569421528899;8617.353698472514;8781.385303028726;8120.6345714443;8340.249377556049;8388.622511986976;8597.140285971353;8829;7035.41007665076;7094.148702155774;7098.503104782616;7160;7308.30571095745;1939.699346601279;2015.5162760937562;2058.7220725202205;2081.760597531303;2084.9895750521455;929.934904556688;939.8684184214216;1019.8062368150028;1054.1830081686753;1081.378207530673;20211.494712632008;20714.48213794667;20781.272655457287;20908.68636970447;20936.267230646856;996.4818294486971;999.7800483893532;1029.1715169288645;1039.8752149741929;1051.3166441812882;10832.729181770519;11478.19652624325;11613.245139065933;11925;11928.224665396721;467587;471620;472744;485815;486646;85062;85987.57006215054;86527.56736216405;86733.56633216793;88150;1000.9749756256152;1002.1431675917132;1022.8107800057013;1025.3950169400089;1029.46467836726;17305.653886922442;17442.302307907543;17601.41041069281;17790.02154881494;17931.19309631096;39226.41160382539;39535.465034895715;40388;41265;41696.79151604223;51431;51938.70153246199;52545.47454525559;54220.186697198784;54327;8502.362322825937;8580.785480363042;8647.048824629412;8651.48091114535;8773.210411063035;11354.489047992694;11677.532898683958;12003.579874704343;12018.579349722717;12249.448774805333;9856.457894815854;10395.84406233892;10447.477626118512;10512.789744205225;10696.839547406951;10611.628592999208;10656.413897235727;10679;10942.45287735626;10978.451077445936;10687.839682404925;10713.464326783474;11018.614348497764;11054.502547385548;11368.488418021043;8601.483910965362;8749.47503149813;8770.166834151001;8829.293656507336;8930.240929520804;10540.314879532805;10782.08352290064;10849.349039057683;10864.456777160953;11206 22:07:26 INFO - PROCESS | 31610 | |3;mootools.html;1410.9929450352759;1477.6749115194652;1496.850314968503;1682.327069172331;1850.5466160790609;8003.91996080039;8584.22741953224;8902.332325075613;8938.19556239939;9141.085891410856;8970.730878073651;9015.729528114167;9043;9048.5928133234;9135.17783399494;2485;2511.3470497670555;2515.8490490570534;2515.8490490570625;2517.980218011708;2438.1588352018525;2500.612405077207;2503.2240005598233;2509.6862892138483;2513.7611926866916;2436.8059650771083;2451.84063035903;2484.1802205272306;2484.3789052736815;2485.229578830559;2352.541254455382;2386.4153282445827;2404.2546810488716;2427.66012758214;2428.477877256398;2461.4461746107163;2474.2206205045354;2476;2498.6876640419946;2499.6750422444966;8829.293656507463;9068.047854975199;9091.727248182566;9177.265818734486;9210.631574737003;5513.669179849222;5750;5754.798582049608;5755.827325180209;5756.366799652035;3515.54297941266;3616.3490571697084;3676.448532720087;3692.40921452569;3694.1688120173017;1057.9629712960086;1073.903348698621;1083.7886612110642;1185.4132204558712;1188.144535934126;13032.478700852049;13088.934555327163;13094.869051309555;13129.868701313055;13185.802212966813;4577.427821522309;4729.456112547101;4837.05677392912;4850.854474365775;4880;10145.695629131138;10153.086222240034;10159.441230732233;10162.796744065225;10165.085142337226;5653.434656534396;5656.236408084938;5657.886842263131;5660.660360378391;5662.971685141548;4438.400815889878;4567.840125595655;4716.056788642285;5185.377754669388;5257.369115706139;3992.9800350998057;3999.3601023836045;4108.815103320358;4122.154958233555;4122.42286079949;1202.1524824998355;1240.8262843201958;1242.3229340009716;1249.5189352099326;1252.2048499203072;931.7950050988771;952.6094301336419;954.9713508594754;962.0043255230803;985.5072463768116;5570.832875013756;5664.320281566238;5880.8532336194885;5916.911246331309;6049.606775559662;480.12856665152634;482.91307564638;491.51585688097197;499.5129748495188;503.393410939824;3923.352646813301;3924.2151569686175;3952.130531283113;4004.4994375703036;4025.959740402617;10738.35569865795;11478.19652624325;11524.30854148737;11575.652730418102;11590.13074019435;11344.149188810707;11374.886251137383;11392.487338069806;11392.886071139348;11412.087033037336;8452.366072544584;8567.785805354915;8726.738197854074;8755.21203091712;8764.824703505894;5485.561155107502;5837.357890632112;5841;5923.85190370227;5958.910616340671;9960.352577082467;9991;10131.7467063324;10158.08577228053;10200.38797672142;9355.672551460666;9644.469554174588;10387.37675739458;10527.947360263455;10531.89468105309;7805.882911756442;7903.881441778493;7999.160088190889;8051.033875934691;8226.136255693305;8791.780205494913;9039.096090390909;9074.04722504154;9076.319276054455;9082.59128339213;7842.568658723825;7980.201979801974;7980.720674776355;8039.1558886318435;8066.959665201636;7332.743353982798;7346.889796652948;7352.816179595553;7368.889466658112;7404;6888.070110535214;6990.755323563651;6991.370776630128;6993.510454268356;6995.580265184105;5076.771545280398;5138.280640710378;5154.819581314786;5194.272801807674;5305.204219367126;5021;5061.898762024813;5134.614903882299;5144.7942082316285;5310.309659744208 22:07:26 INFO - PROCESS | 31610 | |4;prototype.html;351.26234906695925;351.2851347507821;359.4446580033848;362.67359376561075;365.6380183618217;5436.592255580832;5462.481064298895;5470.206820011096;5485.3966063733005;5503.7798488060525;5143.742812859351;5215.018293048645;5292.126799078157;5315.561506142376;5446.863828404292;4235.66114710824;4248.851290204843;4255.702100852942;4267.829286828523;4314.331278651806;4252.957470425303;4278.61492465678;4288.463942007249;4303.225419424502;4305.138972205556;4010.9799451002705;4016.3975403689533;4235.894102647428;4277.796934578694;4283.657307415399;4225.394350146925;4243.702940794145;4249.383839343309;4249.660027197817;4269.7224680395675;3788.9431658525145;3811.6188381161937;3826.789526576037;3834.654881060718;3843.5579908310624;5072.771725272335;5131.230315452712;5193.948060519422;5228.973855130738;5295.629305948585;4865.245886887538;4867.294242334877;4873.829415970424;4878.438580018746;4895.9755201224125;5624.156376543551;5627.437256274381;5643.492085712264;5672.234248376458;5692.288463942007;4129.566395528486;4186.204621121977;4190.658673172089;4198.85304014358;4245.957540424587;5756.856078598027;5769.653820770725;5774.29658250817;5791.480705892908;5819.915444814255;4260.765657888784;4264;4280.336547835091;4331.444447775827;4333.71830830995;4189.490517031629;4312.805923733439;4338.501072376654;4364.41080454141;4368.924760738624;4848.690853469543;4850.345203397496;4859.1496488114435;4862.610991120702;4906.460289368167;5635.774569017275;5646.0683987141665;5651.350094739158;5651.406602306699;5658.773649053992;1363.3251540487522;1417.5605562275728;1423.0963338280267;1452.5111760445627;1476.3356489579733;898.5731777405811;913.6295556665002;973.5229737428609;1002.7292630989592;1003.471252443852;5325.121354976463;5337.19942008694;5347.272890825707;5353.892922141536;5356.92861427716;5629.634073785191;5642.796116892859;5670.837478316935;5699.2590963174525;5703.686297253608;5208.322918020633;5220.92168617471;5241.043012525519;5255.500727430888;5335.093034184198;2934.794564380473;2960.15635543869;2963.851807409621;2964.0959507350467;2980.9729384019074;5526.336839579276;5739.397363276879;5749.821286636229;5758.654480731169;5759.625624334405;5543.501084902378;5584.385717571064;5620.297462817148;5639.492445679908;5647.830565083054;5687.971560142173;5700.800471983461;5720.2849643794525;5754.741036653444;5772.614572502569;5278.392984806796;5519.586031047767;5532.446755324436;5565.248691426767;5573.359063707726;5477.603211181124;5481.725913704219;5507;5508.3114610673665;5510.614257001971;5462.317210348707;5711.771529138788;5726.341470730919;5730.255066841284;5768.913466297925;4056.1076563156057;4071.75569465833;4076.164386300742;4076.2458945095254;4078.5309689384912;5524.558035357081;5530.446955304437;5550.361708403585;5572.690417751906;5600.803971860965;5371.221172929982;5373.516383525502;5392.8674978255;5403.486668766461;5425.484578964992;5652.63257888236;5666.555028467715;5678.346990096192;5687.20379146928;5698.495425313319;5400.567954563547;5536.889262214814;5644.153376993484;5650.40670729584;5653.575981801299;5516.61383703153;5672.234248376416;5709.514691251124;5710.971445142748;5730.828075157585;5547.472990065938;5556.666600004012;5558.44415558441;5563.554915606823;5614.354349249888 22:07:26 INFO - PROCESS | 31610 | |5;yui.html;791.8337149198671;792.7978365516792;837.6649340263893;855.7946092937697;860.7546849147992;14557.675219784862;15755.448559300426;16177.19114044299;16326.591835204126;16375.93556418832;14251.287435628228;14331.211783351911;14453.060551064173;14700.117992920406;14851.183184924852;724.6847621284758;837.7989282572199;837.8701301298294;838.337713206566;840.2983508770184;746.4588173574142;832.7626626411474;860.3891237221549;864.11859902899;868.8522951098329;899.7345782994;900.1673452056854;900.6037343568842;900.9144131307515;901.5266984832949;756.5385115079797;899.33449247557;900.2527901841456;900.2977677411629;900.9909900900973;570.1106274212242;629.2669040567733;644.8710257948383;645.3256347117274;647.3558808985043;13997.930010349985;14079.718405631933;14082.155070695688;14090.647733806634;14092.224927628977;13857.584272471842;14025.5091071812;14190.929045354707;14213.573592792232;14220.573382798533;11863.2288901222;11945.223560468628;12045.962368186629;12202.023838092931;12217.205881617667;4264.722793018444;4269.295566231568;4299.935500967488;4364;4388.42950416447;62998;64073.679631601546;64872.35127648758;65657.67171164161;68506.65746671235;11059;11250.7187320316;11278.605248816417;11547.538098476134;11672.941635291769;20818.687719684218;20819.479513012295;20921.37235882926;20928.267510637055;21130.894345528173;7805.275364909997;8145.389095817837;8280.261085744363;8315.251627353568;8329.625166867505;4253.149370125987;4269.231538323132;4313.654907607384;4393.165298593257;4510.684252102387;3344.3311337732553;3445.913852153666;3457.429524128491;3468.8092154931214;3476.2526056897887;596.2070446306402;600.8587981824268;605.2434456928839;606.5875204860649;607.532200205839;591.9171316015761;616.374380004296;619.8822223777468;705.5978092487233;787.1852632525308;8981.865272020925;9006.504642244608;9016.684416045538;9097.454152750855;9331.253499720005;595.7319206357157;730.846522230327;745.284526854226;745.4409193105171;745.8657441660445;4531.229690952546;4635.142498637762;4653.953460465352;4659.883502912454;4684.718916864999;86784.56607717047;90041.54979225062;90848;91116;95155;78232;78854;83985;87069.12930870737;87302.56348718215;54252.45747542553;74800.62599687076;75623.9999999989;75640.62179689066;75854.24145758583;8885.378023538291;9048.321375896703;9103.453792772454;9137.177654011171;9197.862032069657;49631;50029.74985125051;50527;50575.241371378725;51229.23156152586;42298.577014230694;42508.78745606252;42610.36084458797;48841.755791220814;67538;12910.419031143809;13229.272390018257;13357.13178643385;13385;13596.456141754219;14311.212883291102;14576.198309092677;14626.573050760553;14693.55919322422;14723.263836807902;12387;12794.424250908545;12953;13555;14174.574762757135;12771.74456510846;12920.935395322964;13135.211887286794;13150;13553.525626603021;12930.288834114213;13237.735245294847;13311.933440332736;13330.466781329027;13379.464821407035;9584.568694408908;10319.380837149794;10851.240413171003;11060.391678457761;11118.166137539554;9824;11172.832407514057;11246;11444.427778611203;11512.251703639236 22:07:26 INFO - PROCESS | 31610 | __end_tp_report 22:07:26 INFO - PROCESS | 31610 | __start_cc_report 22:07:26 INFO - PROCESS | 31610 | _x_x_mozilla_cycle_collect,1000 22:07:26 INFO - PROCESS | 31610 | __end_cc_report 22:07:26 INFO - PROCESS | 31610 | __startTimestamp1484719646425__endTimestamp 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | ------- Summary: start ------- 22:07:26 INFO - PROCESS | 31610 | Number of tests: 6 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | [#0] dojo.html Cycles:165 Average:5671.89 Median:5871.06 stddev:649.24 (11.1%) stddev-sans-first:623.27 22:07:26 INFO - PROCESS | 31610 | Values: 3269.1 3273.1 3309.0 3333.6 3338.1 5685.6 5751.5 5763.3 5764.6 5775.7 5739.6 5796.6 5797.7 5798.8 5802.4 5229.9 5232.7 5238.9 5259.7 5302.2 5057.9 5293.8 5299.5 5312.6 5340.8 5042.4 5155.1 5168.7 5185.4 5232.1 5135.8 5174.4 5176.3 5261.0 5271.1 5138.7 5150.3 5214.8 5222.0 5248.3 5362.0 5432.5 5536.1 5575.2 5612.7 6080.4 6101.0 6102.8 6158.6 6185.9 6190.4 6190.7 6203.1 6207.7 6233.7 5441.8 5491.3 5623.5 5755.4 5770.0 6230.5 6249.3 6280.5 6283.1 6290.8 6001.8 6011.5 6028.0 6036.3 6067.3 5871.1 5930.9 6097.4 6115.7 6132.8 6157.7 6165.6 6166.7 6181.7 6182.8 6101.5 6148.7 6157.2 6166.6 6186.8 4527.0 4586.9 4638.2 4698.7 4782.5 4175.9 4189.7 4192.6 4198.5 4206.3 5791.2 5799.5 5800.2 5802.2 5803.2 5835.2 5905.8 6070.0 6167.4 6170.9 5742.5 5782.8 5793.5 5809.6 5822.3 5195.9 5196.5 5202.5 5207.9 5211.1 6127.0 6185.5 6344.8 6386.0 6394.6 6008.9 6117.9 6137.0 6181.3 6188.4 6410.1 6417.6 6421.7 6423.6 6424.1 5701.6 6051.7 6053.3 6053.7 6055.4 5915.2 6068.5 6075.5 6078.5 6160.8 5930.2 5987.2 5988.0 5989.4 6012.3 5977.7 5991.1 5992.6 5992.9 5996.0 5671.0 5794.4 5851.3 5875.8 5890.9 6026.6 6040.8 6043.6 6046.9 6062.4 5634.4 5686.5 5879.1 5883.0 5888.9 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | [#1] ext.html Cycles:180 Average:58636.92 Median:21249.48 stddev:117642.95 (553.6%) stddev-sans-first:117892.23 22:07:26 INFO - PROCESS | 31610 | Values: 584.7 616.7 643.5 657.1 671.7 34432.3 45684.1 46836.5 46872.0 46969.8 46855.3 47359.5 47374.8 47387.0 47424.8 8263.4 8796.0 8949.8 8952.9 8956.6 8164.1 10552.3 10772.8 10774.5 11124.8 10785.8 10843.5 10851.5 10857.6 10864.8 10701.6 10772.0 10772.4 10788.1 10794.4 10658.3 10705.3 10716.4 10730.3 10732.1 44477.8 44806.8 44806.8 44810.8 44839.0 3563.1 3581.2 3628.8 3884.3 4158.2 32779.8 32913.5 33132.2 33228.0 34658.5 4257.7 4328.7 4357.5 4372.8 4412.8 265513.0 267737.0 270590.0 271289.0 272388.0 39040.2 39787.6 63537.4 63756.7 63947.0 40979.6 41140.0 41153.0 41221.2 41223.4 15661.4 15708.7 15717.1 15733.0 15748.9 12703.7 12976.7 12985.8 13006.3 13204.1 9312.5 9403.6 9414.2 9505.7 9522.0 3544.9 3545.5 3598.7 3644.7 3645.5 978.3 996.4 1021.7 1036.7 1085.3 31397.5 33268.0 33455.0 33464.7 33568.5 1082.7 1115.5 1151.5 1204.1 1210.6 16048.3 16358.5 16551.6 16737.7 16755.5 570103.0 573218.0 575752.0 576194.0 576867.0 59909.7 60389.0 60433.4 60515.0 60663.7 1289.5 1327.1 1373.7 1437.0 1449.3 29603.7 33245.8 33588.7 33795.7 34046.1 50411.7 50854.2 50992.7 51018.7 51047.0 426479.0 428179.0 428544.0 429425.0 430239.0 29937.9 30673.8 30941.7 32276.0 32348.8 49613.8 49811.8 50129.2 50634.7 51021.5 41524.8 41646.8 41692.2 42005.6 43095.8 42001.4 42160.4 42273.2 43122.1 43409.8 41867.8 42520.4 42702.6 42808.0 43060.1 35498.3 36657.6 37385.3 38588.8 38614.6 27267.2 28890.9 28950.4 29166.6 29331.6 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | [#2] jquery.html Cycles:180 Average:28766.00 Median:2455.22 stddev:78662.43 (3203.9%) stddev-sans-first:78854.25 22:07:26 INFO - PROCESS | 31610 | Values: 393.1 411.9 426.2 426.7 447.2 15001.9 19022.9 20513.3 20531.4 20677.5 19852.3 20760.4 20810.3 20817.2 20953.2 5696.8 5883.2 5890.1 5894.8 5896.7 5605.9 5783.3 5793.1 5796.3 5802.7 5399.7 5743.0 5760.2 5764.5 5766.8 5670.3 5764.7 5770.5 5772.2 5774.0 5066.7 5224.5 5242.5 5250.4 5250.9 23920.5 24183.0 24192.3 24204.9 24284.6 2438.0 2472.5 2476.5 2554.5 2967.7 14660.1 15221.7 15471.4 15673.0 15953.3 1863.5 1928.6 1930.5 1934.7 1965.5 85088.0 88003.0 89628.0 90810.1 92326.0 10066.2 11120.8 11202.2 11327.8 11368.5 14127.2 14921.9 14961.3 14961.9 14992.6 8398.3 8535.7 8611.6 8617.4 8781.4 8120.6 8340.2 8388.6 8597.1 8829.0 7035.4 7094.1 7098.5 7160.0 7308.3 1939.7 2015.5 2058.7 2081.8 2085.0 929.9 939.9 1019.8 1054.2 1081.4 20211.5 20714.5 20781.3 20908.7 20936.3 996.5 999.8 1029.2 1039.9 1051.3 10832.7 11478.2 11613.2 11925.0 11928.2 467587.0 471620.0 472744.0 485815.0 486646.0 85062.0 85987.6 86527.6 86733.6 88150.0 1001.0 1002.1 1022.8 1025.4 1029.5 17305.7 17442.3 17601.4 17790.0 17931.2 39226.4 39535.5 40388.0 41265.0 41696.8 51431.0 51938.7 52545.5 54220.2 54327.0 8502.4 8580.8 8647.0 8651.5 8773.2 11354.5 11677.5 12003.6 12018.6 12249.4 9856.5 10395.8 10447.5 10512.8 10696.8 10611.6 10656.4 10679.0 10942.5 10978.5 10687.8 10713.5 11018.6 11054.5 11368.5 8601.5 8749.5 8770.2 8829.3 8930.2 10540.3 10782.1 10849.3 10864.5 11206.0 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | [#3] mootools.html Cycles:180 Average:5926.04 Median:2685.76 stddev:3403.95 (126.7%) stddev-sans-first:3396.59 22:07:26 INFO - PROCESS | 31610 | Values: 1411.0 1477.7 1496.9 1682.3 1850.5 8003.9 8584.2 8902.3 8938.2 9141.1 8970.7 9015.7 9043.0 9048.6 9135.2 2485.0 2511.3 2515.8 2515.8 2518.0 2438.2 2500.6 2503.2 2509.7 2513.8 2436.8 2451.8 2484.2 2484.4 2485.2 2352.5 2386.4 2404.3 2427.7 2428.5 2461.4 2474.2 2476.0 2498.7 2499.7 8829.3 9068.0 9091.7 9177.3 9210.6 5513.7 5750.0 5754.8 5755.8 5756.4 3515.5 3616.3 3676.4 3692.4 3694.2 1058.0 1073.9 1083.8 1185.4 1188.1 13032.5 13088.9 13094.9 13129.9 13185.8 4577.4 4729.5 4837.1 4850.9 4880.0 10145.7 10153.1 10159.4 10162.8 10165.1 5653.4 5656.2 5657.9 5660.7 5663.0 4438.4 4567.8 4716.1 5185.4 5257.4 3993.0 3999.4 4108.8 4122.2 4122.4 1202.2 1240.8 1242.3 1249.5 1252.2 931.8 952.6 955.0 962.0 985.5 5570.8 5664.3 5880.9 5916.9 6049.6 480.1 482.9 491.5 499.5 503.4 3923.4 3924.2 3952.1 4004.5 4026.0 10738.4 11478.2 11524.3 11575.7 11590.1 11344.1 11374.9 11392.5 11392.9 11412.1 8452.4 8567.8 8726.7 8755.2 8764.8 5485.6 5837.4 5841.0 5923.9 5958.9 9960.4 9991.0 10131.7 10158.1 10200.4 9355.7 9644.5 10387.4 10527.9 10531.9 7805.9 7903.9 7999.2 8051.0 8226.1 8791.8 9039.1 9074.0 9076.3 9082.6 7842.6 7980.2 7980.7 8039.2 8067.0 7332.7 7346.9 7352.8 7368.9 7404.0 6888.1 6990.8 6991.4 6993.5 6995.6 5076.8 5138.3 5154.8 5194.3 5305.2 5021.0 5061.9 5134.6 5144.8 5310.3 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | [#4] prototype.html Cycles:180 Average:4709.40 Median:5355.41 stddev:1346.35 (25.1%) stddev-sans-first:1309.79 22:07:26 INFO - PROCESS | 31610 | Values: 351.3 351.3 359.4 362.7 365.6 5436.6 5462.5 5470.2 5485.4 5503.8 5143.7 5215.0 5292.1 5315.6 5446.9 4235.7 4248.9 4255.7 4267.8 4314.3 4253.0 4278.6 4288.5 4303.2 4305.1 4011.0 4016.4 4235.9 4277.8 4283.7 4225.4 4243.7 4249.4 4249.7 4269.7 3788.9 3811.6 3826.8 3834.7 3843.6 5072.8 5131.2 5193.9 5229.0 5295.6 4865.2 4867.3 4873.8 4878.4 4896.0 5624.2 5627.4 5643.5 5672.2 5692.3 4129.6 4186.2 4190.7 4198.9 4246.0 5756.9 5769.7 5774.3 5791.5 5819.9 4260.8 4264.0 4280.3 4331.4 4333.7 4189.5 4312.8 4338.5 4364.4 4368.9 4848.7 4850.3 4859.1 4862.6 4906.5 5635.8 5646.1 5651.4 5651.4 5658.8 1363.3 1417.6 1423.1 1452.5 1476.3 898.6 913.6 973.5 1002.7 1003.5 5325.1 5337.2 5347.3 5353.9 5356.9 5629.6 5642.8 5670.8 5699.3 5703.7 5208.3 5220.9 5241.0 5255.5 5335.1 2934.8 2960.2 2963.9 2964.1 2981.0 5526.3 5739.4 5749.8 5758.7 5759.6 5543.5 5584.4 5620.3 5639.5 5647.8 5688.0 5700.8 5720.3 5754.7 5772.6 5278.4 5519.6 5532.4 5565.2 5573.4 5477.6 5481.7 5507.0 5508.3 5510.6 5462.3 5711.8 5726.3 5730.3 5768.9 4056.1 4071.8 4076.2 4076.2 4078.5 5524.6 5530.4 5550.4 5572.7 5600.8 5371.2 5373.5 5392.9 5403.5 5425.5 5652.6 5666.6 5678.3 5687.2 5698.5 5400.6 5536.9 5644.2 5650.4 5653.6 5516.6 5672.2 5709.5 5711.0 5730.8 5547.5 5556.7 5558.4 5563.6 5614.4 22:07:26 INFO - PROCESS | 31610 | 22:07:26 INFO - PROCESS | 31610 | [#5] yui.html Cycles:180 Average:18092.06 Median:26763.24 stddev:24118.85 (90.1%) stddev-sans-first:24151.53 22:07:26 INFO - PROCESS | 31610 | Values: 791.8 792.8 837.7 855.8 860.8 14557.7 15755.4 16177.2 16326.6 16375.9 14251.3 14331.2 14453.1 14700.1 14851.2 724.7 837.8 837.9 838.3 840.3 746.5 832.8 860.4 864.1 868.9 899.7 900.2 900.6 900.9 901.5 756.5 899.3 900.3 900.3 901.0 570.1 629.3 644.9 645.3 647.4 13997.9 14079.7 14082.2 14090.6 14092.2 13857.6 14025.5 14190.9 14213.6 14220.6 11863.2 11945.2 12046.0 12202.0 12217.2 4264.7 4269.3 4299.9 4364.0 4388.4 62998.0 64073.7 64872.4 65657.7 68506.7 11059.0 11250.7 11278.6 11547.5 11672.9 20818.7 20819.5 20921.4 20928.3 21130.9 7805.3 8145.4 8280.3 8315.3 8329.6 4253.1 4269.2 4313.7 4393.2 4510.7 3344.3 3445.9 3457.4 3468.8 3476.3 596.2 600.9 605.2 606.6 607.5 591.9 616.4 619.9 705.6 787.2 8981.9 9006.5 9016.7 9097.5 9331.3 595.7 730.8 745.3 745.4 745.9 4531.2 4635.1 4654.0 4659.9 4684.7 86784.6 90041.5 90848.0 91116.0 95155.0 78232.0 78854.0 83985.0 87069.1 87302.6 54252.5 74800.6 75624.0 75640.6 75854.2 8885.4 9048.3 9103.5 9137.2 9197.9 49631.0 50029.7 50527.0 50575.2 51229.2 42298.6 42508.8 42610.4 48841.8 67538.0 12910.4 13229.3 13357.1 13385.0 13596.5 14311.2 14576.2 14626.6 14693.6 14723.3 12387.0 12794.4 12953.0 13555.0 14174.6 12771.7 12920.9 13135.2 13150.0 13553.5 12930.3 13237.7 13311.9 13330.5 13379.5 9584.6 10319.4 10851.2 11060.4 11118.2 9824.0 11172.8 11246.0 11444.4 11512.3 22:07:26 INFO - PROCESS | 31610 | -------- Summary: end -------- 22:07:26 INFO - PROCESS | 31610 | 22:07:27 INFO - TEST-INFO | 31610: exit 0 22:07:27 INFO - TEST-OK | dromaeo_css | took 1115522ms 22:07:27 INFO - TEST-START | kraken 22:07:27 INFO - operating with platform_type : linux_ 22:07:27 INFO - Initialising browser for kraken test... 22:07:27 INFO - TEST-INFO | started process 31926 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAScDgQ/profile http://localhost:42971/getInfo.html) 22:07:31 INFO - PROCESS | 31926 | __metrics Screen width/height:1600/1200 22:07:31 INFO - PROCESS | 31926 | colorDepth:24 22:07:31 INFO - PROCESS | 31926 | Browser inner width/height: 1024/697 22:07:31 INFO - PROCESS | 31926 | __metrics 22:07:31 INFO - PROCESS | 31926 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:07:31 INFO - PROCESS | 31926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:07:32 INFO - PROCESS | 31926 | console.error: PushService: 22:07:32 INFO - PROCESS | 31926 | stateChangeProcessEnqueue: Error transitioning state 22:07:32 INFO - PROCESS | 31926 | UnknownError 22:07:32 INFO - TEST-INFO | 31926: exit 0 22:07:32 INFO - Browser initialized. 22:07:32 INFO - Running cycle 1/1 for kraken test... 22:07:32 INFO - TEST-INFO | started process 32018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAScDgQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:44 INFO - PROCESS | 32018 | RSS: Main: 146796544 22:07:44 INFO - PROCESS | 32018 | 22:07:47 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42971/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:48 INFO - PROCESS | 32018 | RSS: Main: 188960768 22:07:48 INFO - PROCESS | 32018 | 22:07:50 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42971/tests/kraken/driver.html?testName=audio-dft) 22:07:51 INFO - PROCESS | 32018 | RSS: Main: 178372608 22:07:51 INFO - PROCESS | 32018 | 22:07:53 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42971/tests/kraken/driver.html?testName=audio-fft) 22:07:54 INFO - PROCESS | 32018 | RSS: Main: 269692928 22:07:54 INFO - PROCESS | 32018 | 22:07:56 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42971/tests/kraken/driver.html?testName=audio-oscillator) 22:07:57 INFO - PROCESS | 32018 | RSS: Main: 175890432 22:07:57 INFO - PROCESS | 32018 | 22:07:59 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42971/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:59 INFO - PROCESS | 32018 | RSS: Main: 178761728 22:07:59 INFO - PROCESS | 32018 | 22:08:02 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42971/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:03 INFO - PROCESS | 32018 | RSS: Main: 217927680 22:08:03 INFO - PROCESS | 32018 | 22:08:06 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42971/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:07 INFO - PROCESS | 32018 | RSS: Main: 216399872 22:08:07 INFO - PROCESS | 32018 | 22:08:10 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42971/tests/kraken/driver.html?testName=json-parse-financial) 22:08:10 INFO - PROCESS | 32018 | RSS: Main: 214319104 22:08:10 INFO - PROCESS | 32018 | 22:08:12 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42971/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:08:12 INFO - PROCESS | 32018 | RSS: Main: 176336896 22:08:12 INFO - PROCESS | 32018 | 22:08:14 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:08:14 INFO - PROCESS | 32018 | RSS: Main: 184999936 22:08:14 INFO - PROCESS | 32018 | 22:08:16 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:08:17 INFO - PROCESS | 32018 | RSS: Main: 190455808 22:08:17 INFO - PROCESS | 32018 | 22:08:20 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:08:20 INFO - PROCESS | 32018 | RSS: Main: 199299072 22:08:20 INFO - PROCESS | 32018 | 22:08:23 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:08:24 INFO - PROCESS | 32018 | RSS: Main: 195350528 22:08:24 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | Cycle 1(1): loaded http://localhost:42971/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42971/tests/kraken/driver.html?testName=ai-astar) 22:08:25 INFO - PROCESS | 32018 | RSS: Main: 247017472 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | __start_tp_report 22:08:25 INFO - PROCESS | 32018 | _x_x_mozilla_page_load 22:08:25 INFO - PROCESS | 32018 | _x_x_mozilla_page_load_details 22:08:25 INFO - PROCESS | 32018 | |i|pagename|runs| 22:08:25 INFO - PROCESS | 32018 | |0;ai-astar;102;111;110;110;109;109;109;110;109;110 22:08:25 INFO - PROCESS | 32018 | |1;audio-beat-detection;146;120;118;156;117;122;122;125;151;118 22:08:25 INFO - PROCESS | 32018 | |2;audio-dft;164;160;163;162;159;160;162;159;161;163 22:08:25 INFO - PROCESS | 32018 | |3;audio-fft;75;75;75;115;77;75;75;74;101;75 22:08:25 INFO - PROCESS | 32018 | |4;audio-oscillator;81;81;120;82;82;81;98;82;81;120 22:08:25 INFO - PROCESS | 32018 | |5;imaging-gaussian-blur;117;117;117;116;116;118;116;119;117;117 22:08:25 INFO - PROCESS | 32018 | |6;imaging-darkroom;145;146;146;146;146;146;146;146;145;145 22:08:25 INFO - PROCESS | 32018 | |7;imaging-desaturate;84;84;84;85;84;85;84;84;85;85 22:08:25 INFO - PROCESS | 32018 | |8;json-parse-financial;50;49;50;48;50;49;49;49;49;49 22:08:25 INFO - PROCESS | 32018 | |9;json-stringify-tinderbox;58;58;57;58;58;57;58;58;57;57 22:08:25 INFO - PROCESS | 32018 | |10;stanford-crypto-aes;83;75;75;75;80;79;79;73;74;74 22:08:25 INFO - PROCESS | 32018 | |11;stanford-crypto-ccm;131;135;134;121;122;129;123;126;156;122 22:08:25 INFO - PROCESS | 32018 | |12;stanford-crypto-pbkdf2;153;157;152;152;150;154;155;152;158;154 22:08:25 INFO - PROCESS | 32018 | |13;stanford-crypto-sha256-iterative;66;61;63;61;62;63;61;61;61;62 22:08:25 INFO - PROCESS | 32018 | __end_tp_report 22:08:25 INFO - PROCESS | 32018 | __start_cc_report 22:08:25 INFO - PROCESS | 32018 | _x_x_mozilla_cycle_collect,1590 22:08:25 INFO - PROCESS | 32018 | __end_cc_report 22:08:25 INFO - PROCESS | 32018 | __startTimestamp1484719705913__endTimestamp 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | ------- Summary: start ------- 22:08:25 INFO - PROCESS | 32018 | Number of tests: 14 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.51 (2.3%) stddev-sans-first:0.71 22:08:25 INFO - PROCESS | 32018 | Values: 102.0 111.0 110.0 110.0 109.0 109.0 109.0 110.0 109.0 110.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:123.50 stddev:15.20 (12.3%) stddev-sans-first:14.91 22:08:25 INFO - PROCESS | 32018 | Values: 146.0 120.0 118.0 156.0 117.0 122.0 122.0 125.0 151.0 118.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:1.77 (1.1%) stddev-sans-first:1.58 22:08:25 INFO - PROCESS | 32018 | Values: 164.0 160.0 163.0 162.0 159.0 160.0 162.0 159.0 161.0 163.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:14.27 (19.0%) stddev-sans-first:14.93 22:08:25 INFO - PROCESS | 32018 | Values: 75.0 75.0 75.0 115.0 77.0 75.0 75.0 74.0 101.0 75.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:81.50 stddev:16.24 (19.9%) stddev-sans-first:16.83 22:08:25 INFO - PROCESS | 32018 | Values: 81.0 81.0 120.0 82.0 82.0 81.0 98.0 82.0 81.0 120.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 22:08:25 INFO - PROCESS | 32018 | Values: 117.0 117.0 117.0 116.0 116.0 118.0 116.0 119.0 117.0 117.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 22:08:25 INFO - PROCESS | 32018 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 22:08:25 INFO - PROCESS | 32018 | Values: 84.0 84.0 84.0 85.0 84.0 85.0 84.0 84.0 85.0 85.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.00 stddev:0.63 (1.3%) stddev-sans-first:0.60 22:08:25 INFO - PROCESS | 32018 | Values: 50.0 49.0 50.0 48.0 50.0 49.0 49.0 49.0 49.0 49.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 22:08:25 INFO - PROCESS | 32018 | Values: 58.0 58.0 57.0 58.0 58.0 57.0 58.0 58.0 57.0 57.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:77.00 stddev:3.30 (4.3%) stddev-sans-first:2.60 22:08:25 INFO - PROCESS | 32018 | Values: 83.0 75.0 75.0 75.0 80.0 79.0 79.0 73.0 74.0 74.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:130.00 stddev:10.50 (8.1%) stddev-sans-first:11.13 22:08:25 INFO - PROCESS | 32018 | Values: 131.0 135.0 134.0 121.0 122.0 129.0 123.0 126.0 156.0 122.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:154.00 stddev:2.45 (1.6%) stddev-sans-first:2.59 22:08:25 INFO - PROCESS | 32018 | Values: 153.0 157.0 152.0 152.0 150.0 154.0 155.0 152.0 158.0 154.0 22:08:25 INFO - PROCESS | 32018 | 22:08:25 INFO - PROCESS | 32018 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.60 (2.6%) stddev-sans-first:0.87 22:08:25 INFO - PROCESS | 32018 | Values: 66.0 61.0 63.0 61.0 62.0 63.0 61.0 61.0 61.0 62.0 22:08:25 INFO - PROCESS | 32018 | -------- Summary: end -------- 22:08:25 INFO - PROCESS | 32018 | 22:08:27 INFO - TEST-INFO | 32018: exit 0 22:08:27 INFO - TEST-OK | kraken | took 59988ms 22:08:27 INFO - SUITE-END | took 1175s 22:08:27 INFO - Completed test suite (00:19:35) 22:08:27 INFO - javascript benchmark 22:08:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3269.1009972257634, 3273.1162586101755, 3308.966910330896, 3333.633300336961, 3338.13208565773, 5685.6020078594465, 5751.539876809856, 5763.250777398937, 5764.5676574256995, 5775.682337471438, 5739.626924249931, 5796.594238403313, 5797.71011449428, 5798.768049278035, 5802.419758024195, 5229.921551176735, 5232.686038837663, 5238.92141617875, 5259.684418934857, 5302.1781623848365, 5057.898842023157, 5293.814716484924, 5299.496547827969, 5312.6281160318795, 5340.786368545254, 5042.4201216860165, 5155.097857874875, 5168.689878607277, 5185.351831021122, 5232.058229518685, 5135.768890399941, 5174.379074511064, 5176.301199338079, 5260.973695131538, 5271.130263506517, 5138.6916784992645, 5150.330457040598, 5214.791408343662, 5222.033923724085, 5248.317718696583, 5361.98122356751, 5432.538234250093, 5536.114221724505, 5575.219469274305, 5612.69130197839, 6080.391960803929, 6101.0, 6102.847428814271, 6158.56890017699, 6185.907211391833, 6190.380961903774, 6190.659513726743, 6203.069539569055, 6207.658578778165, 6233.719482623293, 5441.809536666197, 5491.258680078178, 5623.465770751854, 5755.424457554211, 5769.9711501442225, 6230.532710046765, 6249.281332646713, 6280.466160376419, 6283.120363149163, 6290.811275661646, 6001.759929602855, 6011.549133814981, 6028.0355143176885, 6036.261534695243, 6067.302260240041, 5871.060630299217, 5930.8813823723285, 6097.390260973956, 6115.666295175888, 6132.78535251264, 6157.6613286269685, 6165.599236049642, 6166.7224974876235, 6181.660008699565, 6182.814515564541, 6101.542832930421, 6148.6925653717135, 6157.230346206724, 6166.5991710539565, 6186.845328866814, 4527.0493196429115, 4586.908261834745, 4638.234691275901, 4698.718076915396, 4782.52174782519, 4175.895602609899, 4189.664826813848, 4192.643625291823, 4198.517170525429, 4206.284931561642, 5791.247137872049, 5799.536037117021, 5800.187973683687, 5802.158686990447, 5803.187553742479, 5835.241418615553, 5905.76376944926, 6070.028795392716, 6167.444929956326, 6170.938290617037, 5742.511886489695, 5782.768689252467, 5793.536517078624, 5809.622374545641, 5822.272215973004, 5195.948040519622, 5196.506331898464, 5202.453742356998, 5207.895842083137, 5211.140161873249, 6127.0062805303205, 6185.505159587132, 6344.8413789654705, 6386.042093685985, 6394.648294343763, 6008.8798224036145, 6117.938820611737, 6136.969315153574, 6181.258249010148, 6188.412100850412, 6410.134631824644, 6417.550771445954, 6421.7110230038825, 6423.64669943139, 6424.100625912282, 5701.600887937804, 6051.667158306335, 6053.334133245251, 6053.667048312385, 6055.394460553909, 5915.171875937458, 6068.51451883857, 6075.483583895241, 6078.54410919174, 6160.784372546939, 5930.199423078002, 5987.161797348288, 5988.041913294026, 5989.40105989415, 6012.308584512837, 5977.731002104829, 5991.071383935497, 5992.64044157352, 5992.8801423972145, 5995.970020149872, 5671.0, 5794.391588883106, 5851.327097383855, 5875.794347197828, 5890.882182356415, 6026.578139530191, 6040.75836966534, 6043.607165534227, 6046.939530604637, 6062.42406971337, 5634.352049514195, 5686.516646085129, 5879.147523609138, 5883.0293001654245, 5888.852778680567], "value": 5628.072067621306, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.666739958224, 616.740968793107, 643.4916416031333, 657.0997733105646, 671.6775947545175, 34432.31135377291, 45684.08631827366, 46836.5316346836, 46872.0, 46969.76515117419, 46855.29717054247, 47359.526404735945, 47374.76312618432, 47387.0, 47424.762876185654, 8263.380246481507, 8795.956020219906, 8949.77625559363, 8952.8657070144, 8956.641734330618, 8164.061132969696, 10552.314099583502, 10772.784544309108, 10774.515146818412, 11124.833127503094, 10785.83821242682, 10843.457827108652, 10851.457427128651, 10857.619983300587, 10864.837027444595, 10701.625443109493, 10772.0, 10772.353658780497, 10788.08301294383, 10794.352338859655, 10658.253922225447, 10705.25063245573, 10716.41059741714, 10730.302530335503, 10732.141428685687, 44477.77761111206, 44806.77596612029, 44806.77596612029, 44810.77594612006, 44839.0, 3563.1270338767167, 3581.1584277694983, 3628.782273063598, 3884.3408796190924, 4158.189153115144, 32779.83610081958, 32913.506297405555, 33132.17169570756, 33228.00315990524, 34658.48012279818, 4257.74453532789, 4328.675349348811, 4357.477102747659, 4372.781360931973, 4412.823487060546, 265513.0, 267737.0, 270590.0, 271289.0, 272388.0, 39040.2191956165, 39787.60212397897, 63537.36462635407, 63756.681216594545, 63947.0, 40979.590204098175, 41140.0, 41153.0, 41221.1755764889, 41223.38164927528, 15661.373545058297, 15708.68582628341, 15717.056976581212, 15733.0, 15748.92125539365, 12703.68240793969, 12976.67558311031, 12985.805212921814, 13006.284654343912, 13204.141730787467, 9312.48781317034, 9403.57683904226, 9414.2468602513, 9505.66730164441, 9522.047795220422, 3544.929101417957, 3545.503629491873, 3598.676119149237, 3644.671979521856, 3645.4714066460215, 978.295627148452, 996.4419924842111, 1021.7343490692474, 1036.6838114375037, 1085.2565992295144, 31397.52903706446, 33268.0, 33455.0, 33464.657473976586, 33568.49647255293, 1082.653550863716, 1115.481301194932, 1151.5048529132387, 1204.0728638948135, 1210.5883999440232, 16048.27782749779, 16358.509244722678, 16551.586210344838, 16737.748933766004, 16755.497335079966, 570103.0, 573218.0, 575752.0, 576194.0, 576867.0, 59909.70045149746, 60389.0, 60433.39566604366, 60515.0, 60663.69668151719, 1289.548657969703, 1327.1307293722434, 1373.6771858613217, 1437.0, 1449.253634378296, 29603.703962960095, 33245.83377083099, 33588.66411335855, 33795.66204338024, 34046.148846279044, 50411.74794126006, 50854.237186441496, 50992.745036276065, 51018.74490627523, 51047.0, 426479.0, 428179.0, 428544.0, 429425.0, 430239.0, 29937.85031074831, 30673.846630766704, 30941.69058309388, 32276.0, 32348.83825580951, 49613.75193124155, 49811.75094124506, 50129.248061278384, 50634.74682626563, 51021.48978510167, 41524.79237603793, 41646.79176604097, 41692.166156677304, 42005.579944200166, 43095.78452107719, 42001.369979450945, 42160.36759448672, 42273.15453690848, 43122.137557249305, 43409.78295108504, 41867.7906610465, 42520.36219456772, 42702.57297426986, 42808.0, 43060.138797223255, 35498.29003419969, 36657.63342366649, 37385.252294954495, 38588.80705596454, 38614.613853862225, 27267.181984540497, 28890.855545722978, 28950.42099158047, 29166.56250156292, 29331.560026599192], "value": 19737.937262396965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.1354782488142, 411.9032027473543, 426.19661937248287, 426.71623370458644, 447.16380368710514, 15001.924990375046, 19022.858628482285, 20513.282035128774, 20531.38405847827, 20677.483062923435, 19852.305169319076, 20760.377188684324, 20810.271640492585, 20817.16731330745, 20953.16187352504, 5696.800611978571, 5883.23517942669, 5890.145928840316, 5894.793682221123, 5896.734646940898, 5605.943940560583, 5783.334916484616, 5793.13103034544, 5796.275465566804, 5802.738876750535, 5399.676019438826, 5743.0, 5760.164776107483, 5764.452377024176, 5766.826995190151, 5670.291213598301, 5764.711764411804, 5770.451807078321, 5772.220750198711, 5773.971130144343, 5066.721330326831, 5224.4775522447835, 5242.501962313574, 5250.369955605313, 5250.9474905250845, 23920.52158956811, 24183.0, 24192.2742317729, 24204.878975605185, 24284.63573046406, 2437.9878100609385, 2472.4560596668703, 2476.4923190746035, 2554.489102179553, 2967.658719247271, 14660.066532539238, 15221.695566088727, 15471.381144754196, 15673.0, 15953.282102305307, 1863.5341164708823, 1928.6142771445766, 1930.4787707319085, 1934.7291379206922, 1965.4987978065644, 85088.0, 88002.99999999936, 89628.0, 90810.09189908083, 92326.0, 10066.194704423628, 11120.777584448266, 11202.159838012181, 11327.773444531063, 11368.488418021208, 14127.15237085778, 14921.925390372979, 14961.326740296712, 14961.925190374197, 14992.550223493312, 8398.286145677686, 8535.701250456204, 8611.569421528899, 8617.353698472514, 8781.385303028726, 8120.6345714443, 8340.249377556049, 8388.622511986976, 8597.140285971353, 8829.0, 7035.41007665076, 7094.148702155774, 7098.503104782616, 7160.0, 7308.30571095745, 1939.699346601279, 2015.5162760937562, 2058.7220725202205, 2081.760597531303, 2084.9895750521455, 929.934904556688, 939.8684184214216, 1019.8062368150028, 1054.1830081686753, 1081.378207530673, 20211.494712632008, 20714.48213794667, 20781.272655457287, 20908.68636970447, 20936.267230646856, 996.4818294486971, 999.7800483893532, 1029.1715169288645, 1039.8752149741929, 1051.3166441812882, 10832.729181770519, 11478.19652624325, 11613.245139065933, 11925.0, 11928.224665396721, 467587.0, 471620.0, 472744.0, 485815.0, 486646.0, 85062.0, 85987.57006215054, 86527.56736216405, 86733.56633216793, 88150.0, 1000.9749756256152, 1002.1431675917132, 1022.8107800057013, 1025.3950169400089, 1029.46467836726, 17305.653886922442, 17442.302307907543, 17601.41041069281, 17790.02154881494, 17931.19309631096, 39226.41160382539, 39535.465034895715, 40388.0, 41265.0, 41696.79151604223, 51431.0, 51938.70153246199, 52545.47454525559, 54220.186697198784, 54327.0, 8502.362322825937, 8580.785480363042, 8647.048824629412, 8651.48091114535, 8773.210411063035, 11354.489047992694, 11677.532898683958, 12003.579874704343, 12018.579349722717, 12249.448774805333, 9856.457894815854, 10395.84406233892, 10447.477626118512, 10512.789744205225, 10696.839547406951, 10611.628592999208, 10656.413897235727, 10679.0, 10942.45287735626, 10978.451077445936, 10687.839682404925, 10713.464326783474, 11018.614348497764, 11054.502547385548, 11368.488418021043, 8601.483910965362, 8749.47503149813, 8770.166834151001, 8829.293656507336, 8930.240929520804, 10540.314879532805, 10782.08352290064, 10849.349039057683, 10864.456777160953, 11206.0], "value": 9441.661392017093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.9929450352759, 1477.6749115194652, 1496.850314968503, 1682.327069172331, 1850.5466160790609, 8003.91996080039, 8584.22741953224, 8902.332325075613, 8938.19556239939, 9141.085891410856, 8970.730878073651, 9015.729528114167, 9043.0, 9048.5928133234, 9135.17783399494, 2485.0, 2511.3470497670555, 2515.8490490570534, 2515.8490490570625, 2517.980218011708, 2438.1588352018525, 2500.612405077207, 2503.2240005598233, 2509.6862892138483, 2513.7611926866916, 2436.8059650771083, 2451.84063035903, 2484.1802205272306, 2484.3789052736815, 2485.229578830559, 2352.541254455382, 2386.4153282445827, 2404.2546810488716, 2427.66012758214, 2428.477877256398, 2461.4461746107163, 2474.2206205045354, 2476.0, 2498.6876640419946, 2499.6750422444966, 8829.293656507463, 9068.047854975199, 9091.727248182566, 9177.265818734486, 9210.631574737003, 5513.669179849222, 5750.0, 5754.798582049608, 5755.827325180209, 5756.366799652035, 3515.54297941266, 3616.3490571697084, 3676.448532720087, 3692.40921452569, 3694.1688120173017, 1057.9629712960086, 1073.903348698621, 1083.7886612110642, 1185.4132204558712, 1188.144535934126, 13032.478700852049, 13088.934555327163, 13094.869051309555, 13129.868701313055, 13185.802212966813, 4577.427821522309, 4729.456112547101, 4837.05677392912, 4850.854474365775, 4880.0, 10145.695629131138, 10153.086222240034, 10159.441230732233, 10162.796744065225, 10165.085142337226, 5653.434656534396, 5656.236408084938, 5657.886842263131, 5660.660360378391, 5662.971685141548, 4438.400815889878, 4567.840125595655, 4716.056788642285, 5185.377754669388, 5257.369115706139, 3992.9800350998057, 3999.3601023836045, 4108.815103320358, 4122.154958233555, 4122.42286079949, 1202.1524824998355, 1240.8262843201958, 1242.3229340009716, 1249.5189352099326, 1252.2048499203072, 931.7950050988771, 952.6094301336419, 954.9713508594754, 962.0043255230803, 985.5072463768116, 5570.832875013756, 5664.320281566238, 5880.8532336194885, 5916.911246331309, 6049.606775559662, 480.12856665152634, 482.91307564638, 491.51585688097197, 499.5129748495188, 503.393410939824, 3923.352646813301, 3924.2151569686175, 3952.130531283113, 4004.4994375703036, 4025.959740402617, 10738.35569865795, 11478.19652624325, 11524.30854148737, 11575.652730418102, 11590.13074019435, 11344.149188810707, 11374.886251137383, 11392.487338069806, 11392.886071139348, 11412.087033037336, 8452.366072544584, 8567.785805354915, 8726.738197854074, 8755.21203091712, 8764.824703505894, 5485.561155107502, 5837.357890632112, 5841.0, 5923.85190370227, 5958.910616340671, 9960.352577082467, 9991.0, 10131.7467063324, 10158.08577228053, 10200.38797672142, 9355.672551460666, 9644.469554174588, 10387.37675739458, 10527.947360263455, 10531.89468105309, 7805.882911756442, 7903.881441778493, 7999.160088190889, 8051.033875934691, 8226.136255693305, 8791.780205494913, 9039.096090390909, 9074.04722504154, 9076.319276054455, 9082.59128339213, 7842.568658723825, 7980.201979801974, 7980.720674776355, 8039.1558886318435, 8066.959665201636, 7332.743353982798, 7346.889796652948, 7352.816179595553, 7368.889466658112, 7404.0, 6888.070110535214, 6990.755323563651, 6991.370776630128, 6993.510454268356, 6995.580265184105, 5076.771545280398, 5138.280640710378, 5154.819581314786, 5194.272801807674, 5305.204219367126, 5021.0, 5061.898762024813, 5134.614903882299, 5144.7942082316285, 5310.309659744208], "value": 4665.026547509486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.26234906695925, 351.2851347507821, 359.4446580033848, 362.67359376561075, 365.6380183618217, 5436.592255580832, 5462.481064298895, 5470.206820011096, 5485.3966063733005, 5503.7798488060525, 5143.742812859351, 5215.018293048645, 5292.126799078157, 5315.561506142376, 5446.863828404292, 4235.66114710824, 4248.851290204843, 4255.702100852942, 4267.829286828523, 4314.331278651806, 4252.957470425303, 4278.61492465678, 4288.463942007249, 4303.225419424502, 4305.138972205556, 4010.9799451002705, 4016.3975403689533, 4235.894102647428, 4277.796934578694, 4283.657307415399, 4225.394350146925, 4243.702940794145, 4249.383839343309, 4249.660027197817, 4269.7224680395675, 3788.9431658525145, 3811.6188381161937, 3826.789526576037, 3834.654881060718, 3843.5579908310624, 5072.771725272335, 5131.230315452712, 5193.948060519422, 5228.973855130738, 5295.629305948585, 4865.245886887538, 4867.294242334877, 4873.829415970424, 4878.438580018746, 4895.9755201224125, 5624.156376543551, 5627.437256274381, 5643.492085712264, 5672.234248376458, 5692.288463942007, 4129.566395528486, 4186.204621121977, 4190.658673172089, 4198.85304014358, 4245.957540424587, 5756.856078598027, 5769.653820770725, 5774.29658250817, 5791.480705892908, 5819.915444814255, 4260.765657888784, 4264.0, 4280.336547835091, 4331.444447775827, 4333.71830830995, 4189.490517031629, 4312.805923733439, 4338.501072376654, 4364.41080454141, 4368.924760738624, 4848.690853469543, 4850.345203397496, 4859.1496488114435, 4862.610991120702, 4906.460289368167, 5635.774569017275, 5646.0683987141665, 5651.350094739158, 5651.406602306699, 5658.773649053992, 1363.3251540487522, 1417.5605562275728, 1423.0963338280267, 1452.5111760445627, 1476.3356489579733, 898.5731777405811, 913.6295556665002, 973.5229737428609, 1002.7292630989592, 1003.471252443852, 5325.121354976463, 5337.19942008694, 5347.272890825707, 5353.892922141536, 5356.92861427716, 5629.634073785191, 5642.796116892859, 5670.837478316935, 5699.2590963174525, 5703.686297253608, 5208.322918020633, 5220.92168617471, 5241.043012525519, 5255.500727430888, 5335.093034184198, 2934.794564380473, 2960.15635543869, 2963.851807409621, 2964.0959507350467, 2980.9729384019074, 5526.336839579276, 5739.397363276879, 5749.821286636229, 5758.654480731169, 5759.625624334405, 5543.501084902378, 5584.385717571064, 5620.297462817148, 5639.492445679908, 5647.830565083054, 5687.971560142173, 5700.800471983461, 5720.2849643794525, 5754.741036653444, 5772.614572502569, 5278.392984806796, 5519.586031047767, 5532.446755324436, 5565.248691426767, 5573.359063707726, 5477.603211181124, 5481.725913704219, 5507.0, 5508.3114610673665, 5510.614257001971, 5462.317210348707, 5711.771529138788, 5726.341470730919, 5730.255066841284, 5768.913466297925, 4056.1076563156057, 4071.75569465833, 4076.164386300742, 4076.2458945095254, 4078.5309689384912, 5524.558035357081, 5530.446955304437, 5550.361708403585, 5572.690417751906, 5600.803971860965, 5371.221172929982, 5373.516383525502, 5392.8674978255, 5403.486668766461, 5425.484578964992, 5652.63257888236, 5666.555028467715, 5678.346990096192, 5687.20379146928, 5698.495425313319, 5400.567954563547, 5536.889262214814, 5644.153376993484, 5650.40670729584, 5653.575981801299, 5516.61383703153, 5672.234248376416, 5709.514691251124, 5710.971445142748, 5730.828075157585, 5547.472990065938, 5556.666600004012, 5558.44415558441, 5563.554915606823, 5614.354349249888], "value": 4284.0151810155485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.8337149198671, 792.7978365516792, 837.6649340263893, 855.7946092937697, 860.7546849147992, 14557.675219784862, 15755.448559300426, 16177.19114044299, 16326.591835204126, 16375.93556418832, 14251.287435628228, 14331.211783351911, 14453.060551064173, 14700.117992920406, 14851.183184924852, 724.6847621284758, 837.7989282572199, 837.8701301298294, 838.337713206566, 840.2983508770184, 746.4588173574142, 832.7626626411474, 860.3891237221549, 864.11859902899, 868.8522951098329, 899.7345782994, 900.1673452056854, 900.6037343568842, 900.9144131307515, 901.5266984832949, 756.5385115079797, 899.33449247557, 900.2527901841456, 900.2977677411629, 900.9909900900973, 570.1106274212242, 629.2669040567733, 644.8710257948383, 645.3256347117274, 647.3558808985043, 13997.930010349985, 14079.718405631933, 14082.155070695688, 14090.647733806634, 14092.224927628977, 13857.584272471842, 14025.5091071812, 14190.929045354707, 14213.573592792232, 14220.573382798533, 11863.2288901222, 11945.223560468628, 12045.962368186629, 12202.023838092931, 12217.205881617667, 4264.722793018444, 4269.295566231568, 4299.935500967488, 4364.0, 4388.42950416447, 62998.0, 64073.679631601546, 64872.35127648758, 65657.67171164161, 68506.65746671235, 11059.0, 11250.7187320316, 11278.605248816417, 11547.538098476134, 11672.941635291769, 20818.687719684218, 20819.479513012295, 20921.37235882926, 20928.267510637055, 21130.894345528173, 7805.275364909997, 8145.389095817837, 8280.261085744363, 8315.251627353568, 8329.625166867505, 4253.149370125987, 4269.231538323132, 4313.654907607384, 4393.165298593257, 4510.684252102387, 3344.3311337732553, 3445.913852153666, 3457.429524128491, 3468.8092154931214, 3476.2526056897887, 596.2070446306402, 600.8587981824268, 605.2434456928839, 606.5875204860649, 607.532200205839, 591.9171316015761, 616.374380004296, 619.8822223777468, 705.5978092487233, 787.1852632525308, 8981.865272020925, 9006.504642244608, 9016.684416045538, 9097.454152750855, 9331.253499720005, 595.7319206357157, 730.846522230327, 745.284526854226, 745.4409193105171, 745.8657441660445, 4531.229690952546, 4635.142498637762, 4653.953460465352, 4659.883502912454, 4684.718916864999, 86784.56607717047, 90041.54979225062, 90848.0, 91116.0, 95155.0, 78232.0, 78854.0, 83985.0, 87069.12930870737, 87302.56348718215, 54252.45747542553, 74800.62599687076, 75623.9999999989, 75640.62179689066, 75854.24145758583, 8885.378023538291, 9048.321375896703, 9103.453792772454, 9137.177654011171, 9197.862032069657, 49631.0, 50029.74985125051, 50527.0, 50575.241371378725, 51229.23156152586, 42298.577014230694, 42508.78745606252, 42610.36084458797, 48841.755791220814, 67538.0, 12910.419031143809, 13229.272390018257, 13357.13178643385, 13385.0, 13596.456141754219, 14311.212883291102, 14576.198309092677, 14626.573050760553, 14693.55919322422, 14723.263836807902, 12387.0, 12794.424250908545, 12953.0, 13555.0, 14174.574762757135, 12771.74456510846, 12920.935395322964, 13135.211887286794, 13150.0, 13553.525626603021, 12930.288834114213, 13237.735245294847, 13311.933440332736, 13330.466781329027, 13379.464821407035, 9584.568694408908, 10319.380837149794, 10851.240413171003, 11060.391678457761, 11118.166137539554, 9824.0, 11172.832407514057, 11246.0, 11444.427778611203, 11512.251703639236], "value": 7250.562762001743, "unit": "score"}], "name": "dromaeo_css", "value": 7305.303277693577, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 118.0, 156.0, 117.0, 122.0, 122.0, 125.0, 151.0, 118.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 163.0, 162.0, 159.0, 160.0, 162.0, 159.0, 161.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 115.0, 77.0, 75.0, 75.0, 74.0, 101.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 120.0, 82.0, 82.0, 81.0, 98.0, 82.0, 81.0, 120.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 118.0, 116.0, 119.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 84.0, 85.0, 84.0, 84.0, 85.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 48.0, 50.0, 49.0, 49.0, 49.0, 49.0, 49.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 58.0, 58.0, 57.0, 58.0, 58.0, 57.0, 57.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 75.0, 75.0, 80.0, 79.0, 79.0, 73.0, 74.0, 74.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 135.0, 134.0, 121.0, 122.0, 129.0, 123.0, 126.0, 156.0, 122.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 157.0, 152.0, 152.0, 150.0, 154.0, 155.0, 152.0, 158.0, 154.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 63.0, 61.0, 62.0, 63.0, 61.0, 61.0, 61.0, 62.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1448.5000000000002, "alertThreshold": 2.0}]} 22:08:27 INFO - Return code: 0 22:08:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:08:27 INFO - # TBPL SUCCESS # 22:08:27 INFO - Running post-action listener: _resource_record_post_action 22:08:27 INFO - [mozharness: 2017-01-18 06:08:27.718043Z] Finished run-tests step (success) 22:08:27 INFO - Running post-run listener: _resource_record_post_run 22:08:27 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 270336; Write bytes: 356999168; Read time: 200; Write time: 202196 22:08:27 INFO - TinderboxPrint: CPU usage
13.0% 22:08:27 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 200 22:08:27 INFO - TinderboxPrint: I/O write bytes / time
356,999,168 / 202,196 22:08:27 INFO - TinderboxPrint: CPU idle
8,284.1 (87.0%) 22:08:27 INFO - TinderboxPrint: CPU user
1,191.7 (12.5%) 22:08:27 INFO - TinderboxPrint: Swap in / out
0 / 0 22:08:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108367872; Read time: 0; Write time: 67548 22:08:27 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 270336; Write bytes: 248631296; Read time: 200; Write time: 134648 22:08:28 INFO - Running post-run listener: _upload_blobber_files 22:08:28 INFO - Blob upload gear active. 22:08:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:08:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:08:28 INFO - (blobuploader) - INFO - Open directory for files ... 22:08:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:08:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:08:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:29 INFO - (blobuploader) - INFO - Done attempting. 22:08:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:08:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:08:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:32 INFO - (blobuploader) - INFO - Done attempting. 22:08:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:08:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:08:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:33 INFO - (blobuploader) - INFO - Done attempting. 22:08:33 INFO - (blobuploader) - INFO - Iteration through files over. 22:08:33 INFO - Return code: 0 22:08:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:08:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:08:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11b309d89091c30be19441095073ecf3c07491f60082e43fc8b45ceed47842347cd8424da7d4a45a71fa84c5613addb7b81c10b409e79dc4376778a6293ca538", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc65f470ee2a687c740e7f57348539e6d8f0adf9c1355c5df90608c5fbcc88d4d5835c11e4d7c1304c7a1227ecd43c25bab9bc6e2ccde3e88f1f5024105a1a06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdcd2d9d070e016c3cfbb2affa7f3da492a54cc5b48326ae96ed09f0dd4ff7b0aa77bf81a6515fddcabe225a1720cbd86462e0101d21c6c56c6dfa189db0561b"} 22:08:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:08:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:08:33 INFO - Contents: 22:08:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11b309d89091c30be19441095073ecf3c07491f60082e43fc8b45ceed47842347cd8424da7d4a45a71fa84c5613addb7b81c10b409e79dc4376778a6293ca538", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc65f470ee2a687c740e7f57348539e6d8f0adf9c1355c5df90608c5fbcc88d4d5835c11e4d7c1304c7a1227ecd43c25bab9bc6e2ccde3e88f1f5024105a1a06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdcd2d9d070e016c3cfbb2affa7f3da492a54cc5b48326ae96ed09f0dd4ff7b0aa77bf81a6515fddcabe225a1720cbd86462e0101d21c6c56c6dfa189db0561b"} 22:08:33 INFO - Running post-run listener: copy_logs_to_upload_dir 22:08:33 INFO - Copying logs to upload dir... 22:08:33 INFO - mkdir: /builds/slave/test/build/upload/logs 22:08:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.637784 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-01-17 22:08:33.668945) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 22:08:33.672228) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11b309d89091c30be19441095073ecf3c07491f60082e43fc8b45ceed47842347cd8424da7d4a45a71fa84c5613addb7b81c10b409e79dc4376778a6293ca538", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc65f470ee2a687c740e7f57348539e6d8f0adf9c1355c5df90608c5fbcc88d4d5835c11e4d7c1304c7a1227ecd43c25bab9bc6e2ccde3e88f1f5024105a1a06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdcd2d9d070e016c3cfbb2affa7f3da492a54cc5b48326ae96ed09f0dd4ff7b0aa77bf81a6515fddcabe225a1720cbd86462e0101d21c6c56c6dfa189db0561b"} build_url:https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011470 build_url: 'https://queue.taskcluster.net/v1/task/UVw24XD7RTKcirlfNy47yg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11b309d89091c30be19441095073ecf3c07491f60082e43fc8b45ceed47842347cd8424da7d4a45a71fa84c5613addb7b81c10b409e79dc4376778a6293ca538", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc65f470ee2a687c740e7f57348539e6d8f0adf9c1355c5df90608c5fbcc88d4d5835c11e4d7c1304c7a1227ecd43c25bab9bc6e2ccde3e88f1f5024105a1a06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdcd2d9d070e016c3cfbb2affa7f3da492a54cc5b48326ae96ed09f0dd4ff7b0aa77bf81a6515fddcabe225a1720cbd86462e0101d21c6c56c6dfa189db0561b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 22:08:33.700687) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 22:08:33.701101) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1484717628.770906-965833518 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 22:08:33.782474) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 22:08:33.782875) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 22:08:33.783196) ========= ========= Total master_lag: 0.17 =========