builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1465946884.55 results: success (0) buildid: 20160614133054 builduid: fcd2e73300e84f128b5048584409dabd revision: 248457986c11278a57928e75218f4fce5fc8ac60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.548202) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.548843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.549195) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.569666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.570072) ========= 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=db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-14 16:28:05-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.1M=0s 2016-06-14 16:28:05 (67.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162843 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.769255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.769628) ========= 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=db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015614 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.835283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:04.835711) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 248457986c11278a57928e75218f4fce5fc8ac60 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 248457986c11278a57928e75218f4fce5fc8ac60 --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=db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-14 16:28:05,515 truncating revision to first 12 chars 2016-06-14 16:28:05,515 Setting DEBUG logging. 2016-06-14 16:28:05,515 attempt 1/10 2016-06-14 16:28:05,515 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/248457986c11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-14 16:28:06,016 unpacking tar archive at: mozilla-inbound-248457986c11/testing/mozharness/ program finished with exit code 0 elapsedTime=0.877398 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:05.744504) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:05.745278) ========= script_repo_revision: 248457986c11278a57928e75218f4fce5fc8ac60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:05.746299) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:05.746787) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 16:28:05.758187) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-06-14 16:28:05.758677) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:28:06 INFO - MultiFileLogger online at 20160614 16:28:06 in /builds/slave/test 16:28:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:28:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:06 INFO - {'append_to_log': False, 16:28:06 INFO - 'base_work_dir': '/builds/slave/test', 16:28:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:28:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:06 INFO - 'branch': 'Mozilla-Inbound', 16:28:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:06 INFO - 'config_files': ('talos/linux_config.py',), 16:28:06 INFO - 'default_actions': ('clobber', 16:28:06 INFO - 'read-buildbot-config', 16:28:06 INFO - 'download-and-extract', 16:28:06 INFO - 'populate-webroot', 16:28:06 INFO - 'create-virtualenv', 16:28:06 INFO - 'install', 16:28:06 INFO - 'run-tests'), 16:28:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:06 INFO - 'download_minidump_stackwalk': True, 16:28:06 INFO - 'download_symbols': 'ondemand', 16:28:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:06 INFO - 'installer_path': 'installer.exe', 16:28:06 INFO - 'log_level': 'info', 16:28:06 INFO - 'log_name': 'talos', 16:28:06 INFO - 'log_to_console': True, 16:28:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:28:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:28:06 INFO - 'opt_config_files': (), 16:28:06 INFO - 'pip_index': False, 16:28:06 INFO - 'sps_profile': False, 16:28:06 INFO - 'sps_profile_interval': 0, 16:28:06 INFO - 'suite': 'dromaeojs', 16:28:06 INFO - 'system_bits': '32', 16:28:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:06 INFO - 'title': 'talos-linux64-ix-031', 16:28:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:06 INFO - 'use_talos_json': True, 16:28:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:06 INFO - 'work_dir': 'build'} 16:28:06 INFO - ##### 16:28:06 INFO - ##### Running clobber step. 16:28:06 INFO - ##### 16:28:06 INFO - Running pre-action listener: _resource_record_pre_action 16:28:06 INFO - Running main action method: clobber 16:28:06 INFO - rmtree: /builds/slave/test/build 16:28:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:06 INFO - Running post-action listener: _resource_record_post_action 16:28:06 INFO - ##### 16:28:06 INFO - ##### Finished clobber step (success) 16:28:06 INFO - ##### 16:28:06 INFO - ##### 16:28:06 INFO - ##### Running read-buildbot-config step. 16:28:06 INFO - ##### 16:28:06 INFO - Running pre-action listener: _resource_record_pre_action 16:28:06 INFO - Running main action method: read_buildbot_config 16:28:06 INFO - Using buildbot properties: 16:28:06 INFO - { 16:28:06 INFO - "project": "", 16:28:06 INFO - "product": "firefox", 16:28:06 INFO - "script_repo_revision": "production", 16:28:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:28:06 INFO - "repository": "", 16:28:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:28:06 INFO - "buildid": "20160614133054", 16:28:06 INFO - "pgo_build": "True", 16:28:06 INFO - "basedir": "/builds/slave/test", 16:28:06 INFO - "buildnumber": 1179, 16:28:06 INFO - "slavename": "talos-linux64-ix-031", 16:28:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:28:06 INFO - "platform": "ubuntu64_hw", 16:28:06 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:28:06 INFO - "branch": "mozilla-inbound", 16:28:06 INFO - "repo_path": "integration/mozilla-inbound", 16:28:06 INFO - "revision": "248457986c11278a57928e75218f4fce5fc8ac60", 16:28:06 INFO - "stage_platform": "linux64-pgo", 16:28:06 INFO - "builduid": "fcd2e73300e84f128b5048584409dabd", 16:28:06 INFO - "slavebuilddir": "test" 16:28:06 INFO - } 16:28:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:28:06 INFO - Running post-action listener: _resource_record_post_action 16:28:06 INFO - ##### 16:28:06 INFO - ##### Finished read-buildbot-config step (success) 16:28:06 INFO - ##### 16:28:06 INFO - ##### 16:28:06 INFO - ##### Running download-and-extract step. 16:28:06 INFO - ##### 16:28:06 INFO - Running pre-action listener: _resource_record_pre_action 16:28:06 INFO - Running main action method: download_and_extract 16:28:06 INFO - mkdir: /builds/slave/test/build/tests 16:28:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:06 INFO - https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:28:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:28:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:28:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:28:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:28:17 INFO - Downloaded 1361 bytes. 16:28:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:28:17 INFO - Using the following test package requirements: 16:28:17 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:28:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:28:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:28:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'jsshell-linux-x86_64.zip'], 16:28:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:28:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:28:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:28:17 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:28:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:28:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:28:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:28:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:17 INFO - https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:28:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:28:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:28:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:28:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:28:26 INFO - Downloaded 24220706 bytes. 16:28:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:28:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:28:27 INFO - Return code: 0 16:28:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:28:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:27 INFO - https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:28:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:28:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:28:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:28:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:28:34 INFO - Downloaded 24220706 bytes. 16:28:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:28:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:28:35 INFO - Return code: 0 16:28:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:35 INFO - https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:28:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:28:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:28:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:28:37 INFO - Downloaded 14405384 bytes. 16:28:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:28:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:28:37 INFO - Return code: 0 16:28:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:37 INFO - https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:28:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:28:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:28:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:28:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:28:39 INFO - Downloaded 59386674 bytes. 16:28:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:28:39 INFO - mkdir: /builds/slave/test/properties 16:28:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:28:39 INFO - Writing to file /builds/slave/test/properties/build_url 16:28:39 INFO - Contents: 16:28:39 INFO - build_url:https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:28:39 INFO - Running post-action listener: _resource_record_post_action 16:28:39 INFO - Running post-action listener: set_extra_try_arguments 16:28:39 INFO - ##### 16:28:39 INFO - ##### Finished download-and-extract step (success) 16:28:39 INFO - ##### 16:28:39 INFO - ##### 16:28:39 INFO - ##### Running populate-webroot step. 16:28:39 INFO - ##### 16:28:39 INFO - Running pre-action listener: _resource_record_pre_action 16:28:39 INFO - Running main action method: populate_webroot 16:28:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:28:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:28:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:28:39 INFO - '--tppagecycles', 16:28:39 INFO - '7'], 16:28:39 INFO - 'tests': ['tsvgm']}}, 16:28:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:28:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:28:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:28:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:28:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['damp', 'tps']}, 16:28:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'tests': ['damp', 'tps']}, 16:28:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['dromaeo_dom']}, 16:28:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:28:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['basic_compositor_video']}, 16:28:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:28:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['a11yr', 16:28:39 INFO - 'ts_paint', 16:28:39 INFO - 'tpaint', 16:28:39 INFO - 'sessionrestore', 16:28:39 INFO - 'sessionrestore_no_auto_restore', 16:28:39 INFO - 'tabpaint']}, 16:28:39 INFO - 'other-e10s': {'tests': ['a11yr', 16:28:39 INFO - 'ts_paint', 16:28:39 INFO - 'tpaint', 16:28:39 INFO - 'sessionrestore', 16:28:39 INFO - 'sessionrestore_no_auto_restore', 16:28:39 INFO - 'tabpaint']}, 16:28:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:28:39 INFO - 'ts_paint', 16:28:39 INFO - 'tpaint', 16:28:39 INFO - 'sessionrestore', 16:28:39 INFO - 'sessionrestore_no_auto_restore', 16:28:39 INFO - 'tabpaint']}, 16:28:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:28:39 INFO - 'ts_paint', 16:28:39 INFO - 'tpaint', 16:28:39 INFO - 'sessionrestore', 16:28:39 INFO - 'sessionrestore_no_auto_restore', 16:28:39 INFO - 'tabpaint']}, 16:28:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['a11yr', 16:28:39 INFO - 'ts_paint', 16:28:39 INFO - 'tpaint', 16:28:39 INFO - 'sessionrestore', 16:28:39 INFO - 'sessionrestore_no_auto_restore', 16:28:39 INFO - 'tabpaint']}, 16:28:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['a11yr', 16:28:39 INFO - 'ts_paint', 16:28:39 INFO - 'tpaint', 16:28:39 INFO - 'sessionrestore', 16:28:39 INFO - 'sessionrestore_no_auto_restore', 16:28:39 INFO - 'tabpaint']}, 16:28:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['tsvgx', 16:28:39 INFO - 'tsvgr_opacity', 16:28:39 INFO - 'tart', 16:28:39 INFO - 'tscrollx', 16:28:39 INFO - 'cart']}, 16:28:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:28:39 INFO - 'tsvgr_opacity', 16:28:39 INFO - 'tart', 16:28:39 INFO - 'tscrollx', 16:28:39 INFO - 'cart']}, 16:28:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'talos_options': ['--disable-e10s'], 16:28:39 INFO - 'tests': ['tp5o']}, 16:28:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'tests': ['tp5o']}, 16:28:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'talos_options': ['--disable-e10s', 16:28:39 INFO - '--xperf_path', 16:28:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:39 INFO - 'tests': ['tp5n']}, 16:28:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:39 INFO - 'talos_options': ['--xperf_path', 16:28:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:39 INFO - 'tests': ['tp5n']}}, 16:28:39 INFO - 'talos.zip': {'path': '', 16:28:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:28:39 INFO - Running post-action listener: _resource_record_post_action 16:28:39 INFO - ##### 16:28:39 INFO - ##### Finished populate-webroot step (success) 16:28:39 INFO - ##### 16:28:39 INFO - ##### 16:28:39 INFO - ##### Running create-virtualenv step. 16:28:39 INFO - ##### 16:28:39 INFO - Running pre-action listener: _resource_record_pre_action 16:28:39 INFO - Running main action method: create_virtualenv 16:28:39 INFO - Creating virtualenv /builds/slave/test/build/venv 16:28:39 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 16:28:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:28:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:28:39 INFO - Using real prefix '/usr' 16:28:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:28:40 INFO - Installing distribute.............................................................................................................................................................................................done. 16:28:42 INFO - Installing pip.................done. 16:28:42 INFO - Return code: 0 16:28:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:28:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:42 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:42 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 16:28:42 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 16:28:42 INFO - Using env: {'DISPLAY': ':0', 16:28:42 INFO - 'HOME': '/home/cltbld', 16:28:42 INFO - 'LANG': 'en_US.UTF-8', 16:28:42 INFO - 'LANGUAGE': 'en_US:en', 16:28:42 INFO - 'LOGNAME': 'cltbld', 16:28:42 INFO - 'MAIL': '/var/mail/cltbld', 16:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:42 INFO - 'MOZ_NO_REMOTE': '1', 16:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:42 INFO - 'NO_EM_RESTART': '1', 16:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:42 INFO - 'PWD': '/builds/slave/test', 16:28:42 INFO - 'SHELL': '/bin/bash', 16:28:42 INFO - 'SHLVL': '1', 16:28:42 INFO - 'TERM': 'linux', 16:28:42 INFO - 'TMOUT': '86400', 16:28:42 INFO - 'USER': 'cltbld', 16:28:42 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:42 INFO - '_': '/tools/buildbot/bin/python'} 16:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:42 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 16:28:42 INFO - Cleaning up... 16:28:42 INFO - Return code: 0 16:28:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:28:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:42 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:42 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 16:28:42 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 16:28:42 INFO - Using env: {'DISPLAY': ':0', 16:28:42 INFO - 'HOME': '/home/cltbld', 16:28:42 INFO - 'LANG': 'en_US.UTF-8', 16:28:42 INFO - 'LANGUAGE': 'en_US:en', 16:28:42 INFO - 'LOGNAME': 'cltbld', 16:28:42 INFO - 'MAIL': '/var/mail/cltbld', 16:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:42 INFO - 'MOZ_NO_REMOTE': '1', 16:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:42 INFO - 'NO_EM_RESTART': '1', 16:28:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:42 INFO - 'PWD': '/builds/slave/test', 16:28:42 INFO - 'SHELL': '/bin/bash', 16:28:42 INFO - 'SHLVL': '1', 16:28:42 INFO - 'TERM': 'linux', 16:28:42 INFO - 'TMOUT': '86400', 16:28:42 INFO - 'USER': 'cltbld', 16:28:42 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:42 INFO - '_': '/tools/buildbot/bin/python'} 16:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:42 INFO - Downloading/unpacking psutil>=3.1.1 16:28:42 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 16:28:42 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 16:28:42 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 16:28:42 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 16:28:42 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 16:28:42 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 16:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:28:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:44 INFO - Installing collected packages: psutil 16:28:44 INFO - Running setup.py install for psutil 16:28:44 INFO - building 'psutil._psutil_linux' extension 16:28:44 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 16:28:45 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 16:28:45 INFO - building 'psutil._psutil_posix' extension 16:28:45 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 16:28:45 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 16:28:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:45 INFO - Successfully installed psutil 16:28:45 INFO - Cleaning up... 16:28:45 INFO - Return code: 0 16:28:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:28:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:45 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:45 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 16:28:45 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 16:28:45 INFO - Using env: {'DISPLAY': ':0', 16:28:45 INFO - 'HOME': '/home/cltbld', 16:28:45 INFO - 'LANG': 'en_US.UTF-8', 16:28:45 INFO - 'LANGUAGE': 'en_US:en', 16:28:45 INFO - 'LOGNAME': 'cltbld', 16:28:45 INFO - 'MAIL': '/var/mail/cltbld', 16:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:45 INFO - 'MOZ_NO_REMOTE': '1', 16:28:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:45 INFO - 'NO_EM_RESTART': '1', 16:28:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:45 INFO - 'PWD': '/builds/slave/test', 16:28:45 INFO - 'SHELL': '/bin/bash', 16:28:45 INFO - 'SHLVL': '1', 16:28:45 INFO - 'TERM': 'linux', 16:28:45 INFO - 'TMOUT': '86400', 16:28:45 INFO - 'USER': 'cltbld', 16:28:45 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:45 INFO - '_': '/tools/buildbot/bin/python'} 16:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:28:45 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 16:28:45 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 16:28:45 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 16:28:45 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 16:28:45 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 16:28:45 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 16:28:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:28:47 INFO - Installing collected packages: mozsystemmonitor 16:28:47 INFO - Running setup.py install for mozsystemmonitor 16:28:47 INFO - Successfully installed mozsystemmonitor 16:28:47 INFO - Cleaning up... 16:28:47 INFO - Return code: 0 16:28:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:28:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:47 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:47 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 16:28:47 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 16:28:47 INFO - Using env: {'DISPLAY': ':0', 16:28:47 INFO - 'HOME': '/home/cltbld', 16:28:47 INFO - 'LANG': 'en_US.UTF-8', 16:28:47 INFO - 'LANGUAGE': 'en_US:en', 16:28:47 INFO - 'LOGNAME': 'cltbld', 16:28:47 INFO - 'MAIL': '/var/mail/cltbld', 16:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:47 INFO - 'MOZ_NO_REMOTE': '1', 16:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:47 INFO - 'NO_EM_RESTART': '1', 16:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:47 INFO - 'PWD': '/builds/slave/test', 16:28:47 INFO - 'SHELL': '/bin/bash', 16:28:47 INFO - 'SHLVL': '1', 16:28:47 INFO - 'TERM': 'linux', 16:28:47 INFO - 'TMOUT': '86400', 16:28:47 INFO - 'USER': 'cltbld', 16:28:47 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:47 INFO - '_': '/tools/buildbot/bin/python'} 16:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:47 INFO - Downloading/unpacking jsonschema==2.5.1 16:28:47 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 16:28:47 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 16:28:47 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 16:28:47 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 16:28:47 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 16:28:47 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 16:28:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:28:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:28:49 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 16:28:49 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 16:28:49 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 16:28:49 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 16:28:49 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 16:28:49 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 16:28:50 INFO - Downloading functools32-3.2.3-2.tar.gz 16:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:28:50 INFO - warning: no files found matching '*.txt' 16:28:50 INFO - no previously-included directories found matching 'build' 16:28:50 INFO - no previously-included directories found matching 'dist' 16:28:50 INFO - no previously-included directories found matching '.git*' 16:28:50 INFO - Installing collected packages: jsonschema, functools32 16:28:50 INFO - Running setup.py install for functools32 16:28:50 INFO - warning: no files found matching '*.txt' 16:28:50 INFO - no previously-included directories found matching 'build' 16:28:50 INFO - no previously-included directories found matching 'dist' 16:28:50 INFO - no previously-included directories found matching '.git*' 16:28:50 INFO - Successfully installed jsonschema functools32 16:28:50 INFO - Cleaning up... 16:28:50 INFO - Return code: 0 16:28:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:28:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:50 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:50 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 16:28:50 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 16:28:50 INFO - Using env: {'DISPLAY': ':0', 16:28:50 INFO - 'HOME': '/home/cltbld', 16:28:50 INFO - 'LANG': 'en_US.UTF-8', 16:28:50 INFO - 'LANGUAGE': 'en_US:en', 16:28:50 INFO - 'LOGNAME': 'cltbld', 16:28:50 INFO - 'MAIL': '/var/mail/cltbld', 16:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:50 INFO - 'MOZ_NO_REMOTE': '1', 16:28:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:50 INFO - 'NO_EM_RESTART': '1', 16:28:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:50 INFO - 'PWD': '/builds/slave/test', 16:28:50 INFO - 'SHELL': '/bin/bash', 16:28:50 INFO - 'SHLVL': '1', 16:28:50 INFO - 'TERM': 'linux', 16:28:50 INFO - 'TMOUT': '86400', 16:28:50 INFO - 'USER': 'cltbld', 16:28:50 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:50 INFO - '_': '/tools/buildbot/bin/python'} 16:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:50 INFO - Downloading/unpacking blobuploader==1.2.4 16:28:50 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 16:28:50 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 16:28:50 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 16:28:50 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 16:28:50 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 16:28:50 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 16:28:52 INFO - Downloading blobuploader-1.2.4.tar.gz 16:28:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:28:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 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 16:28:52 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 16:28:53 INFO - Downloading docopt-0.6.1.tar.gz 16:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:53 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:53 INFO - Running setup.py install for blobuploader 16:28:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:53 INFO - Running setup.py install for requests 16:28:53 INFO - Running setup.py install for docopt 16:28:53 INFO - Successfully installed blobuploader requests docopt 16:28:53 INFO - Cleaning up... 16:28:53 INFO - Return code: 0 16:28:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:53 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:53 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 16:28:53 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 16:28:53 INFO - Using env: {'DISPLAY': ':0', 16:28:53 INFO - 'HOME': '/home/cltbld', 16:28:53 INFO - 'LANG': 'en_US.UTF-8', 16:28:53 INFO - 'LANGUAGE': 'en_US:en', 16:28:53 INFO - 'LOGNAME': 'cltbld', 16:28:53 INFO - 'MAIL': '/var/mail/cltbld', 16:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:53 INFO - 'MOZ_NO_REMOTE': '1', 16:28:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:53 INFO - 'NO_EM_RESTART': '1', 16:28:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:53 INFO - 'PWD': '/builds/slave/test', 16:28:53 INFO - 'SHELL': '/bin/bash', 16:28:53 INFO - 'SHLVL': '1', 16:28:53 INFO - 'TERM': 'linux', 16:28:53 INFO - 'TMOUT': '86400', 16:28:53 INFO - 'USER': 'cltbld', 16:28:53 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:53 INFO - '_': '/tools/buildbot/bin/python'} 16:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:53 INFO - Running setup.py (path:/tmp/pip-GxdpS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:54 INFO - Running setup.py (path:/tmp/pip-7cWJbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:54 INFO - Running setup.py (path:/tmp/pip-PIcR_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:54 INFO - Running setup.py (path:/tmp/pip-y1_lVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:54 INFO - Running setup.py (path:/tmp/pip-Put6cO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:54 INFO - Running setup.py (path:/tmp/pip-4H40MP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:54 INFO - Running setup.py (path:/tmp/pip-1jBRgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:54 INFO - Running setup.py (path:/tmp/pip-urCXti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:54 INFO - Running setup.py (path:/tmp/pip-ZTfpkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:54 INFO - Running setup.py (path:/tmp/pip-Gf1V8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:54 INFO - Running setup.py (path:/tmp/pip-PkOzzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:54 INFO - Running setup.py (path:/tmp/pip-OBzi8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:54 INFO - Running setup.py (path:/tmp/pip-R_OMLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:54 INFO - Running setup.py (path:/tmp/pip-aqSank-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:55 INFO - Running setup.py (path:/tmp/pip-bvkOUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:55 INFO - Running setup.py (path:/tmp/pip-7Z2qXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:55 INFO - Running setup.py (path:/tmp/pip-XCJLrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:55 INFO - Running setup.py install for manifestparser 16:28:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:55 INFO - Running setup.py install for mozcrash 16:28:55 INFO - Running setup.py install for mozdebug 16:28:55 INFO - Running setup.py install for mozdevice 16:28:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:28:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:55 INFO - Running setup.py install for mozfile 16:28:55 INFO - Running setup.py install for mozhttpd 16:28:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:55 INFO - Running setup.py install for mozinfo 16:28:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:55 INFO - Running setup.py install for mozInstall 16:28:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Running setup.py install for mozleak 16:28:56 INFO - Running setup.py install for mozlog 16:28:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Running setup.py install for moznetwork 16:28:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Running setup.py install for mozprocess 16:28:56 INFO - Running setup.py install for mozprofile 16:28:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Running setup.py install for mozrunner 16:28:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:56 INFO - Running setup.py install for mozscreenshot 16:28:56 INFO - Running setup.py install for moztest 16:28:56 INFO - Running setup.py install for mozversion 16:28:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:57 INFO - Cleaning up... 16:28:57 INFO - Return code: 0 16:28:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:57 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:57 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:28:57 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 16:28:57 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 16:28:57 INFO - Using env: {'DISPLAY': ':0', 16:28:57 INFO - 'HOME': '/home/cltbld', 16:28:57 INFO - 'LANG': 'en_US.UTF-8', 16:28:57 INFO - 'LANGUAGE': 'en_US:en', 16:28:57 INFO - 'LOGNAME': 'cltbld', 16:28:57 INFO - 'MAIL': '/var/mail/cltbld', 16:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:57 INFO - 'MOZ_NO_REMOTE': '1', 16:28:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:57 INFO - 'NO_EM_RESTART': '1', 16:28:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:57 INFO - 'PWD': '/builds/slave/test', 16:28:57 INFO - 'SHELL': '/bin/bash', 16:28:57 INFO - 'SHLVL': '1', 16:28:57 INFO - 'TERM': 'linux', 16:28:57 INFO - 'TMOUT': '86400', 16:28:57 INFO - 'USER': 'cltbld', 16:28:57 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:57 INFO - '_': '/tools/buildbot/bin/python'} 16:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:57 INFO - Running setup.py (path:/tmp/pip-SOjlYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:57 INFO - Running setup.py (path:/tmp/pip-jSvoSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:57 INFO - Running setup.py (path:/tmp/pip-8TNq00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:57 INFO - Running setup.py (path:/tmp/pip-Drw6Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:57 INFO - Running setup.py (path:/tmp/pip-27TCYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:57 INFO - Running setup.py (path:/tmp/pip-CNbsV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:57 INFO - Running setup.py (path:/tmp/pip-uLyAjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:57 INFO - Running setup.py (path:/tmp/pip-gEmTZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:57 INFO - Running setup.py (path:/tmp/pip-NA2YC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:57 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:57 INFO - Running setup.py (path:/tmp/pip-GDSClL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:57 INFO - Running setup.py (path:/tmp/pip-Gl_idP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:58 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)) 16:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:58 INFO - Running setup.py (path:/tmp/pip-u93rwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:58 INFO - Running setup.py (path:/tmp/pip-RBetDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:58 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)) 16:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:58 INFO - Running setup.py (path:/tmp/pip-oqDnBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:58 INFO - Running setup.py (path:/tmp/pip-32iJ3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:58 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)) 16:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:58 INFO - Running setup.py (path:/tmp/pip-dlU5DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 16:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:58 INFO - Running setup.py (path:/tmp/pip-rB4Yqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:58 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)) 16:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:28:58 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)) 16:28:58 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)) 16:28:58 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)) 16:28:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:58 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 16:28:58 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 16:28:58 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 16:28:58 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 16:28:58 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 16:28:58 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 16:29:00 INFO - Downloading blessings-1.6.tar.gz 16:29:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:00 INFO - Installing collected packages: blessings 16:29:00 INFO - Running setup.py install for blessings 16:29:00 INFO - Successfully installed blessings 16:29:00 INFO - Cleaning up... 16:29:00 INFO - Return code: 0 16:29:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:00 INFO - Reading from file tmpfile_stdout 16:29:00 INFO - Current package versions: 16:29:00 INFO - argparse == 1.2.1 16:29:00 INFO - blessings == 1.6 16:29:00 INFO - blobuploader == 1.2.4 16:29:00 INFO - docopt == 0.6.1 16:29:00 INFO - functools32 == 3.2.3-2 16:29:00 INFO - jsonschema == 2.5.1 16:29:00 INFO - manifestparser == 1.1 16:29:00 INFO - mozInstall == 1.12 16:29:00 INFO - mozcrash == 0.17 16:29:00 INFO - mozdebug == 0.1 16:29:00 INFO - mozdevice == 0.48 16:29:00 INFO - mozfile == 1.2 16:29:00 INFO - mozhttpd == 0.7 16:29:00 INFO - mozinfo == 0.9 16:29:00 INFO - mozleak == 0.1 16:29:00 INFO - mozlog == 3.2 16:29:00 INFO - moznetwork == 0.27 16:29:00 INFO - mozprocess == 0.22 16:29:00 INFO - mozprofile == 0.28 16:29:00 INFO - mozrunner == 6.11 16:29:00 INFO - mozscreenshot == 0.1 16:29:00 INFO - mozsystemmonitor == 0.3 16:29:00 INFO - moztest == 0.7 16:29:00 INFO - mozversion == 1.4 16:29:00 INFO - psutil == 3.1.1 16:29:00 INFO - requests == 1.2.3 16:29:00 INFO - wsgiref == 0.1.2 16:29:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:00 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:29:00 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 16:29:00 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 16:29:00 INFO - Using env: {'DISPLAY': ':0', 16:29:00 INFO - 'HOME': '/home/cltbld', 16:29:00 INFO - 'LANG': 'en_US.UTF-8', 16:29:00 INFO - 'LANGUAGE': 'en_US:en', 16:29:00 INFO - 'LOGNAME': 'cltbld', 16:29:00 INFO - 'MAIL': '/var/mail/cltbld', 16:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:00 INFO - 'MOZ_NO_REMOTE': '1', 16:29:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:00 INFO - 'NO_EM_RESTART': '1', 16:29:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:00 INFO - 'PWD': '/builds/slave/test', 16:29:00 INFO - 'SHELL': '/bin/bash', 16:29:00 INFO - 'SHLVL': '1', 16:29:00 INFO - 'TERM': 'linux', 16:29:00 INFO - 'TMOUT': '86400', 16:29:00 INFO - 'USER': 'cltbld', 16:29:00 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:00 INFO - '_': '/tools/buildbot/bin/python'} 16:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 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)) 16:29:01 INFO - Cleaning up... 16:29:01 INFO - Return code: 0 16:29:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:01 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 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:29:01 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 16:29:01 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 16:29:01 INFO - Using env: {'DISPLAY': ':0', 16:29:01 INFO - 'HOME': '/home/cltbld', 16:29:01 INFO - 'LANG': 'en_US.UTF-8', 16:29:01 INFO - 'LANGUAGE': 'en_US:en', 16:29:01 INFO - 'LOGNAME': 'cltbld', 16:29:01 INFO - 'MAIL': '/var/mail/cltbld', 16:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:01 INFO - 'MOZ_NO_REMOTE': '1', 16:29:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:01 INFO - 'NO_EM_RESTART': '1', 16:29:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:01 INFO - 'PWD': '/builds/slave/test', 16:29:01 INFO - 'SHELL': '/bin/bash', 16:29:01 INFO - 'SHLVL': '1', 16:29:01 INFO - 'TERM': 'linux', 16:29:01 INFO - 'TMOUT': '86400', 16:29:01 INFO - 'USER': 'cltbld', 16:29:01 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:01 INFO - '_': '/tools/buildbot/bin/python'} 16:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:01 INFO - Cleaning up... 16:29:01 INFO - Return code: 0 16:29:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:29:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:01 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f284a16df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2393350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, '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': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', '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 16:29:01 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', 'flake8'] in /builds/slave/test/build 16:29:01 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 flake8 16:29:01 INFO - Using env: {'DISPLAY': ':0', 16:29:01 INFO - 'HOME': '/home/cltbld', 16:29:01 INFO - 'LANG': 'en_US.UTF-8', 16:29:01 INFO - 'LANGUAGE': 'en_US:en', 16:29:01 INFO - 'LOGNAME': 'cltbld', 16:29:01 INFO - 'MAIL': '/var/mail/cltbld', 16:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:01 INFO - 'MOZ_NO_REMOTE': '1', 16:29:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:01 INFO - 'NO_EM_RESTART': '1', 16:29:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:01 INFO - 'PWD': '/builds/slave/test', 16:29:01 INFO - 'SHELL': '/bin/bash', 16:29:01 INFO - 'SHLVL': '1', 16:29:01 INFO - 'TERM': 'linux', 16:29:01 INFO - 'TMOUT': '86400', 16:29:01 INFO - 'USER': 'cltbld', 16:29:01 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:01 INFO - '_': '/tools/buildbot/bin/python'} 16:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:01 INFO - Downloading/unpacking flake8 16:29:01 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 16:29:01 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 16:29:01 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 16:29:01 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 16:29:01 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 16:29:01 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 16:29:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:29:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:29:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:03 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 16:29:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:29:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:29:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:29:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:29:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:29:04 INFO - no previously-included directories found matching 'docs/_build' 16:29:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:29:04 INFO - Running setup.py install for pep8 16:29:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:29:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:29:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:29:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:29:04 INFO - no previously-included directories found matching 'docs/_build' 16:29:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:29:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:29:04 INFO - Cleaning up... 16:29:04 INFO - Return code: 0 16:29:04 INFO - Running post-action listener: _resource_record_post_action 16:29:04 INFO - Running post-action listener: _start_resource_monitoring 16:29:04 INFO - Starting resource monitoring. 16:29:04 INFO - ##### 16:29:04 INFO - ##### Finished create-virtualenv step (success) 16:29:04 INFO - ##### 16:29:04 INFO - ##### 16:29:04 INFO - ##### Running install step. 16:29:04 INFO - ##### 16:29:04 INFO - Running pre-action listener: _resource_record_pre_action 16:29:04 INFO - Running main action method: install 16:29:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:04 INFO - Reading from file tmpfile_stdout 16:29:04 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:04 INFO - Reading from file tmpfile_stdout 16:29:04 INFO - Output received: 16:29:04 INFO - Usage: mozinstall [options] installer 16:29:04 INFO - Options: 16:29:04 INFO - -h, --help show this help message and exit 16:29:04 INFO - -d DEST, --destination=DEST 16:29:04 INFO - Directory to install application into. [default: 16:29:04 INFO - "/builds/slave/test"] 16:29:04 INFO - --app=APP Application being installed. [default: firefox] 16:29:04 INFO - mkdir: /builds/slave/test/build/application 16:29:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:29:21 INFO - Reading from file tmpfile_stdout 16:29:21 INFO - Output received: 16:29:21 INFO - /builds/slave/test/build/application/firefox/firefox 16:29:21 INFO - Running post-action listener: _resource_record_post_action 16:29:21 INFO - ##### 16:29:21 INFO - ##### Finished install step (success) 16:29:21 INFO - ##### 16:29:21 INFO - ##### 16:29:21 INFO - ##### Running run-tests step. 16:29:21 INFO - ##### 16:29:21 INFO - Running pre-action listener: _resource_record_pre_action 16:29:21 INFO - Running main action method: run_tests 16:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:29:21 INFO - Python 2.7.3 16:29:21 INFO - Return code: 0 16:29:21 INFO - grabbing minidump binary from tooltool 16:29:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:21 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 0x24221c0>, '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 0x24230c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2423550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:29:21 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 16:29:21 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 16:29:21 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 16:29:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:29:21 INFO - Return code: 0 16:29:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:29:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:29:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:29:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:29:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:29:22 INFO - Return code: 0 16:29:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 16:29:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 16:29:22 INFO - Using env: {'DISPLAY': ':0', 16:29:22 INFO - 'HOME': '/home/cltbld', 16:29:22 INFO - 'LANG': 'en_US.UTF-8', 16:29:22 INFO - 'LANGUAGE': 'en_US:en', 16:29:22 INFO - 'LOGNAME': 'cltbld', 16:29:22 INFO - 'MAIL': '/var/mail/cltbld', 16:29:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:29:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:22 INFO - 'MOZ_NO_REMOTE': '1', 16:29:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:22 INFO - 'NO_EM_RESTART': '1', 16:29:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:22 INFO - 'PWD': '/builds/slave/test', 16:29:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:29:22 INFO - 'SHELL': '/bin/bash', 16:29:22 INFO - 'SHLVL': '1', 16:29:22 INFO - 'TERM': 'linux', 16:29:22 INFO - 'TMOUT': '86400', 16:29:22 INFO - 'USER': 'cltbld', 16:29:22 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767', 16:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:22 INFO - '_': '/tools/buildbot/bin/python'} 16:29:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 16:29:22 INFO - mozversion application_buildid: 20160614133054 16:29:22 INFO - mozversion application_changeset: 248457986c11278a57928e75218f4fce5fc8ac60 16:29:22 INFO - mozversion application_display_name: Nightly 16:29:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:29:22 INFO - mozversion application_name: Firefox 16:29:22 INFO - mozversion application_remotingname: firefox 16:29:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:29:22 INFO - mozversion application_vendor: Mozilla 16:29:22 INFO - mozversion application_version: 50.0a1 16:29:22 INFO - mozversion platform_buildid: 20160614133054 16:29:22 INFO - mozversion platform_changeset: 248457986c11278a57928e75218f4fce5fc8ac60 16:29:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:29:22 INFO - mozversion platform_version: 50.0a1 16:29:22 INFO - using testdate: 1465946962 16:29:22 INFO - actual date: 1465946962 16:29:22 INFO - starting webserver on 'localhost:44462' 16:29:22 INFO - SUITE-START | Running 2 tests 16:29:22 INFO - TEST-START | dromaeo_css 16:29:22 INFO - operating with platform_type : linux_ 16:29:22 INFO - Initialising browser for dromaeo_css test... 16:29:22 INFO - TEST-INFO | started process 14449 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY45PsK/profile http://localhost:44462/getInfo.html) 16:29:26 INFO - PROCESS | 14449 | __metrics Screen width/height:1600/1200 16:29:26 INFO - PROCESS | 14449 | colorDepth:24 16:29:26 INFO - PROCESS | 14449 | Browser inner width/height: 1024/697 16:29:26 INFO - PROCESS | 14449 | __metrics 16:29:27 INFO - PROCESS | 14449 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:28 INFO - PROCESS | 14449 | console.error: PushService: 16:29:28 INFO - PROCESS | 14449 | stateChangeProcessEnqueue: Error transitioning state 16:29:28 INFO - PROCESS | 14449 | UnknownError 16:29:28 INFO - TEST-INFO | 14449: exit 0 16:29:28 INFO - Browser initialized. 16:29:28 INFO - Running cycle 1/1 for dromaeo_css test... 16:29:28 INFO - TEST-INFO | started process 14529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY45PsK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:40 INFO - PROCESS | 14529 | RSS: Main: 173830144 16:29:40 INFO - PROCESS | 14529 | 16:30:31 INFO - PROCESS | 14529 | 1465947031532 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:32:40 INFO - PROCESS | 14529 | Cycle 1(1): loaded http://localhost:44462/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44462/tests/dromaeo/cssquery-ext.html) 16:32:41 INFO - PROCESS | 14529 | RSS: Main: 297168896 16:32:41 INFO - PROCESS | 14529 | 16:35:44 INFO - PROCESS | 14529 | Cycle 1(1): loaded http://localhost:44462/tests/dromaeo/cssquery-ext.html (next: http://localhost:44462/tests/dromaeo/cssquery-jquery.html) 16:35:44 INFO - PROCESS | 14529 | RSS: Main: 259321856 16:35:44 INFO - PROCESS | 14529 | 16:38:47 INFO - PROCESS | 14529 | Cycle 1(1): loaded http://localhost:44462/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44462/tests/dromaeo/cssquery-mootools.html) 16:38:47 INFO - PROCESS | 14529 | RSS: Main: 255934464 16:38:47 INFO - PROCESS | 14529 | 16:41:49 INFO - PROCESS | 14529 | Cycle 1(1): loaded http://localhost:44462/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44462/tests/dromaeo/cssquery-prototype.html) 16:41:50 INFO - PROCESS | 14529 | RSS: Main: 255844352 16:41:50 INFO - PROCESS | 14529 | 16:44:52 INFO - PROCESS | 14529 | Cycle 1(1): loaded http://localhost:44462/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44462/tests/dromaeo/cssquery-yui.html) 16:44:53 INFO - PROCESS | 14529 | RSS: Main: 221814784 16:44:53 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | Cycle 1(1): loaded http://localhost:44462/tests/dromaeo/cssquery-yui.html (next: http://localhost:44462/tests/dromaeo/cssquery-dojo.html) 16:47:56 INFO - PROCESS | 14529 | RSS: Main: 294187008 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | __start_tp_report 16:47:56 INFO - PROCESS | 14529 | _x_x_mozilla_page_load 16:47:56 INFO - PROCESS | 14529 | _x_x_mozilla_page_load_details 16:47:56 INFO - PROCESS | 14529 | |i|pagename|runs| 16:47:56 INFO - PROCESS | 14529 | |0;dojo.html;3105.658377578466;3119.937601247974;3129.295908420605;3155.2427417419826;3178.459661857484;5294.9470505294985;5335.146376579748;5373.570114390849;5467.043267428199;5478.958997790417;5404;5421.051316019701;5437.564994800407;5449.373322067963;5490.450954904508;4852.757362131888;4866.805327786884;4928.063667903087;4953.603711703073;5138.743062846861;5049.520295571915;5066.138756411419;5073.315102461176;5112.437631860493;5115.488451154892;4801.279808028806;4854.781534830906;4902.289168070645;4959;4961.255811628249;4823.734694591796;4831.202851529493;4850.029994001214;4909.239067944439;4954;4890.902181956341;4898.946726453794;4899.044686286175;4932.260160975882;4961.131801934647;5261.684298942076;5332.360116786011;5386.676799392049;5402.540784033362;5434.83695489136;5841.269841269841;5843.941560584425;5863.237779088691;5870;5874.706264686749;5854.092615644539;5894.17481552583;5905.645661260338;5918.467337939563;5918.467337939563;5281.15501519755;5316.73416329182;5317.494837990384;5371.355437347544;5405.054115529806;6004.489618382487;6008.669523176267;6019.578629495893;6026.819195424145;6027.60820546663;5724.255846739898;5763.538916886639;5774.855628609234;5775.797847075332;5789.88420231593;5530.695811730313;5727.914081288784;5748.310202775694;5838.5329173666005;5848.4151584841175;5755.884882302331;5808.244928159313;5836.299644042657;5849.181114643953;5861.472467477947;5801.970990145107;5804.100364443518;5816.825495235065;5818.8254352369495;5820.679862607512;4332.913341733148;4369.737815731066;4432.711873728197;4443.644508439317;4473.217186992263;3898.1229223424502;3925.7055720821054;3943.4676318697184;3953.0710283083454;3972.940405893914;5523.861903452445;5528.55771538276;5530.446955304437;5562.72186390687;5562.7774889004795;5587.38538760736;5813.040848260074;5838.4453476919625;5860.355360910296;5923.970380148072;5492;5530.087535556588;5543.52880005204;5552.3892371839065;5604.943950560442;5070.873228169252;5077.873053173626;5082.542571168538;5087.821926232564;5137.152369859006;5749.626274292157;5987.401259873977;5994.580379373402;6062.454379105901;6095.024796032613;5873.618214816023;5879.2944846618675;5882.441168089196;5884.382139875252;5900.262467191601;6038.607490513278;6043.637381757108;6054.727537260922;6060.090986351906;6061.848453788514;5514.61397702157;5662.405447427961;5677.744501497526;5699.974004679198;5757.740901659519;5868.2958045033165;5868.501177399969;5869.383714709894;5879.20630714848;5880.8235752927485;5684.914726279192;5736.569757268138;5739;5741.282339707536;5743.167240750151;5450.509454149145;5677;5696.088625819849;5737.8565535861935;5768.480836724715;5602.411746766531;5621.269234999424;5654.434556544313;5660.066089095418;5662.660240385425;5583.860403489945;5800.332961709458;5855.443732845373;5859.121131830089;5863.208466857091;5439.238506609157;5487.451254874481;5575.330960284792;5587.161925711176;5631.577631677558 16:47:56 INFO - PROCESS | 14529 | |1;ext.html;576.708762075152;616.2050954268993;636.0491065856545;639.4628512049877;649.0815496073056;40024.19951600966;41442.17115657685;41467.37798933015;41496;44416.3337549937;42745.14509709812;42786.57213427873;43039.139217215634;43102.7844860776;43148.784256078674;8885.422447540905;8993.550322483883;9018.278537716968;9021.819563608724;9025.368224224307;8878.067802880701;8996.910030899673;9005.414648047888;9006.099390061006;9006.639734410648;9022.684206052789;9056.773580660503;9058.094190580921;9062.275017998578;9065.22945549629;8927.46435213886;8956.507392093432;8986.865197022082;8992.370534062618;8994.550272486415;8968.955155224246;9044.457332560001;9051.321150913642;9052.59263333145;9057.094290570956;41376.793116034525;41594;41639.375409368884;41692.37461438081;41776;3546.4680297955256;3570.428731402963;3621.8189090545366;3723.5345581802276;3727.552693676749;31692.524612130837;31962;32171.6782832171;32175.83912080448;33496;4364.869053928387;4366.672499562545;4393.384926110347;4495.550444955478;4495.64034877209;339352;339416;339724;340091;340335;56468.717656411456;56556.434435655116;56893.43106568964;56950.715246423504;56958.430415695315;59911.700441497516;60100.69949650224;60196.097058544154;60222.39777602255;60274.698626506586;14999.400023999136;16214.27035783369;16360.590985225464;16366.099864507567;16501.33994640225;11454.94272528632;11643.06855451562;11643.126765492621;11643.243189192675;11646.650600481998;17134.485965421056;17265.913670431568;17318.913405432893;17322.913385432992;17353;5696.715164241771;6383.553151279366;6461.2892581816905;6624;6860.382565569123;915.2769312243254;978.554757585297;1098.3629494892944;1135.968355877118;1159.686884541169;31394.21514462157;31785.205369865474;31853.52219716706;32306.515402268986;32524.837375813444;1080.8000519903842;1134.8581427321585;1156.0982433701727;1194.9283043017447;1232.8335674683983;16138.112403817902;16454.83545164557;16719.331226750794;16782.160891955355;17018.574535636708;514753;515141;515846;520287;523734;56881.14678279829;56965.71517142388;57037;57090.14364784532;57106.00386959392;1245.850497940253;1299.259422129377;1357.314556149158;1362.3869258833565;1368.0560413314781;30822.69177308198;32540.186495337806;32730.67269327276;32985;33282.33435331328;48793.2681009778;49088.75455622699;49278.260826086924;49285.753571231915;49336;314783;316586;316653;317126;317237.44862962444;38743.2251354977;40549.98625034398;41121;41208.1758364837;44694.3295850556;48176.518234818606;48374.516254837;48910.755446223964;49174.75412622914;49245.75377123091;39688.80155599319;40734.38898416585;41169.1766164681;41448.792756036026;42168;39347.21305573815;39519.2096158081;39633.40549891696;40247.79876100601;40486.392704110054;40410.595894040685;40435;40704.3894341591;41238.1752364945;41321.380179296735;34257.828710857284;35922.64077359193;36030.279394412486;36256;36427.27145457129;27127.72872271252;27593.724062759116;27640.447191055664;27692.169234922985;28038 16:47:56 INFO - PROCESS | 14529 | |2;jquery.html;373.54054512949074;375.1729079383403;390.8592906553641;397.40786228519516;397.58055251709453;15290.159041252755;16657.416990405338;17635.20641571129;19905.203791848326;20088.196472141117;18004.459866204;18647.347342843004;19232;19281.517962050955;19294.517637059045;5387;5444.3194600674915;5645;5675.489205971462;5680.6591604503865;4070.14526949341;4276.957230427703;4288.892777680552;4329.718568293051;5508.614396992212;4303.720258183223;5875.764969401219;6134.570580059375;6199.876002479948;6306.558540902139;6006.609570377911;6182.103594978703;6195.783147589857;6198.752049917998;6253.7185826637915;5415.783368665248;5457.2359869618285;5463.562914966793;5468;5470.781168753245;21256;21698.34904952854;21758.12967481299;22506.437339066655;23458.061677532878;2491.078301028631;2588.59876719107;2820.6615206175184;3097.117321563366;3119.7972131811584;13691.452341906312;14225.857741422557;14436.855631443761;14971.251437428195;15054.623634409118;1845.455590600776;1895.6966885298425;1908.3702378215155;1952.1410579345184;1958.7257783910263;68915.3108468919;78369.60815195944;85170.57414712886;86951;88422;9754.317197796086;9776.364536305118;9894.356866803635;9923.7022889312;9950.65172718966;13301.201927884164;13386.464541418429;13682.042257041912;13761.724765504634;13796.310184490736;7772.145064042951;7969.681212751541;7999.800004999922;8013.959930200312;8165.665851187364;8066.467371199454;8110.6211943969665;8212.260896519223;8271.462354946909;8303.252707256375;4648.883777905512;4654.37165982588;4682.367880336179;4730.763461826895;4819.108464933998;1684.1831711619855;1748.1783561725968;1750.6016757042678;1773.7073382891936;1790.7492950986873;775.3603277296253;947.9289053321029;1012.3116280928891;1029.9794004119876;1059.2161800267747;18561;19161.52096197606;19341;19530.511737206398;19730;920.397139873384;938.4134915677702;973.1047436358567;985.9507024648739;1018.9796204076025;10623.20325975555;11119.610813621646;11132.276402033842;11146.721331966766;11162.944185279022;490636;490996;493351;496025;496057;91153.54423227841;92672.53663731772;93186.53406733059;93845.08471695178;94456.52771736235;958.3339578992533;964.5418426247617;965.2374624047064;996.1151509114511;1009.6769033909079;17151.656966860843;17227.3970411035;17478.650426991135;17480.30078796834;17591.736123957893;42145.36781948212;42357.788211058745;42488.57511424846;42850;43044.13911721685;57032.44126925457;58442.4155758437;58909.41090589039;59253.703731481066;59638.614455421295;8369.454487345867;8551.144885511649;8551.657933682583;8711.606143017087;8893.110688931056;9595.952020239854;13251.734965300448;13382.799258010942;13462.844789543899;13829.723405532035;8152.877706834521;11374.488148033524;11377.374244416636;11577.884221157681;11717.882821172021;11621.186516943733;11773.117016223647;11924.463399147233;11947.522099115939;12017.038636909201;6624.6025238485845;10975.50610222558;11863.169578129447;12202;12297.56958506448;7070.858582828418;7685.884711729441;7916.84166316659;8423.199796019368;8545;10242.743931401774;12068.698282542655;12209.328486933304;12378.566750164062;12380.380980951097 16:47:56 INFO - PROCESS | 14529 | |3;mootools.html;1286.0740267007752;1427.3514091224633;1464.6118778523694;1500.7148641758065;1701.455534229047;8170.8365832683385;8324.375671824615;8347.290480309173;8783.472991620507;8800.691975780848;8511.148885111486;8674.91325086749;8706.608202630881;8707.564621768903;8721.825563488743;2001.5396458814482;2217.2904670505445;2274.101729816721;2284.7486776454616;2316.5019520803025;2353.235198560466;2361.8819059047064;2363.61000434928;2364.243442098529;2365.8462199957034;2281.064763446987;2295.150794206146;2298.0463107810237;2303.09027933966;2318.9652155217686;1945.085809669453;2178.9128434862587;2211.159759291467;2214.1143542582936;2230.8326875484404;2259.8644081355174;2280.1335492512903;2300.620397634388;2300.7124109486313;2306.1928325086255;8738.694145704934;8758.605862736193;8807.691730789456;8808.163224493663;8834.204921557026;5102.9489705103215;5247.606429517801;5250.527452529291;5253.7635806388425;5254.737263136866;3136.6863313668678;3263.885763998249;3295.1597342677705;3314.7182489488423;3315.751318651111;1094.5402930769096;1130.5703832543663;1138.1179585820971;1153.9076873850156;1167.6788883057143;11978.820317695241;12004.69988250292;12050;12053.57812476559;12054.939725301492;4436.64506839452;4468.123388176973;4561.498235194126;4591.655625828076;4602.631789456836;10276.383416995004;10302.639407620698;10308.639197628047;10310.690679279634;10311.742206444898;5124.231365295235;5126.666766660155;5128.640995130305;5133.743312834344;5134.5635620972635;4302.752201861487;4653.488116307203;4746.288056791509;4753.809847606057;4880.658353915192;3864.8840534784003;3890.533136023695;3917.216556688674;4010.7593544387432;4053.8986525336513;1207.8007128823647;1230.3540641163281;1232.7534493101236;1232.975340493185;1241.075398827861;923.7736754495191;944.7401964459829;958.0993865766158;960.4045491794992;991.682661548312;5854.2974843018255;5976.760929562855;6066.817995460143;6084;6168.383161683796;440.7245471580262;442.99557004430187;450.62372918613323;454.332131766303;459.4807867110211;3811.19964807388;3967.206558688274;3977.204559088194;3982.6614737747036;3999;10250.58997640086;10668.30656007357;10718.571257149782;10749.892501075046;10788.622398216025;10587.258891877495;10720.892791071989;10731;10734.355938643706;10896;8031.196880312039;8072.4349295548745;8125.512469251864;8164.714235001747;8171.468854524437;5903.763849445974;5921;5968.820935371946;6044.15381846533;6174.38256174379;9407.153356197701;9929.156021737945;9932;9938.850917236103;10037.34757240777;9618.941916389052;9906;10056.29605927578;10100.040496152853;10110.69667909964;7006.563654450927;7443.590602516913;7480.177180510031;7708.537487750753;7909.683612655429;8593.226609604915;8597.140285971353;8687.91312086871;8740.956295218482;8742.694005709769;7590.1650818408825;7819.648115834687;7842.451028427955;8058.83882322362;8098.51408915467;7675.194104618935;7715.22847715224;7800.45396822217;7844.254795794391;7931;7164.534305270141;7182.497225194186;7287.708491660274;7315.268473152855;7394.519356241827;4847.442544107332;4913.508649135058;4983.576396006235;5062.316587260672;5096.643234973664;5218.24335471347;5315.04329220744;5337.492938170868;5347.304850369583;5374.48942350476 16:47:56 INFO - PROCESS | 14529 | |4;prototype.html;314.4371574880962;322.1286420233269;330.58511567982174;331.48123187212025;333.75969302102493;5281.339832520935;5402.621816472849;5437.891242175159;5465.453454654533;5546.779708464142;5346.583155463795;5370.597205209615;5387.49150237934;5411.594130440213;5419.268398766166;4139.234241665286;4173.332266837307;4180.226658068252;4180.9338618652355;4197.8530751423705;3912.7065470089715;4121.423000779893;4140.130572579762;4185.434966279544;4191.853285135021;4059.5534491205945;4090.386442033689;4100.261952848486;4102.938455923164;4103.1793641271715;4099.1801639672185;4115.238680844039;4124.566920473367;4136.4415803866395;4156.355764856422;3618.6743193112484;3632.7093832493338;3638.9272214555826;3640.4357324614725;3655.250673611903;5084.7457627118865;5185.818496352609;5186.455422180655;5191;5196.740162991835;4708.929366059512;4719.150552900476;4722.929156062662;4723.574878260939;4723.740194289313;5438.402869474391;5522.585806064561;5543.1408131739645;5547.44525547446;5547.583931205136;3877.476540667002;3958.2281455116263;3959.544652364986;3970.3647416413237;3986.7009974252046;5529.806456773994;5581.30233720789;5696.829095127153;5719.485246327808;5744.4829965303325;4009.67922566194;4022.577629348935;4029.2143032108456;4077.55146933837;4084.4281800547947;3929.25344184604;4011.4584531088512;4104.056067104582;4137.8965525862095;4146.4402305688345;4645.907081858344;4676.649251306166;4730.314104454835;4777.474477807438;4781.856544303677;5521.723913804293;5535.197396377503;5553.888922221533;5605.990921634064;5627.352854421712;1357.5376462096726;1434.2255182201702;1435;1435.454527279627;1437.1664434628099;890.6214858685033;905.307439808547;917.8714979902819;919.8988111307751;928.9581968811419;4823.831165909247;4838.225883858566;4845.394325709286;4850.248211527219;4858.708477491291;5115.437301896863;5161.4322424533275;5179.948200517946;5181.549166233461;5186.766595503212;4684.8360307389075;4746.677325871903;4772.5943294819635;4787.257975013878;5101.311322971352;3011.2020314616643;3037.559553864677;3055.7860949733304;3072.86304067492;3080.430120427683;5859.970700146558;5876.882462350729;5896;5934.19888315072;5969.239074473041;5621.550275977912;5912.438318359749;5936.871994321152;5938.079597662369;5953.553483488756;5942.227510423617;5942.524598032147;5978.790742324171;5984.935197356818;5993.760249590055;5365.409804921534;5480.314960629921;5767.741226207179;5777.50891174238;5792.247007888947;5702.543796496353;5726.284214473191;5731.684757338387;5735.225744524436;5747.770089196552;5917.319508256605;5946.702664866652;5953;5956.463918247378;5984.251968503937;3838.9232215356096;4074.124063326399;4077.816498257645;4083.8162282697945;4092.242935057024;5585.664860108406;5644.971775141098;5670.177824215548;5681.573881958952;5683.2895888014;5564.33228012641;5574.693391863487;5594.38461769197;5621.606487545832;5655.4910058094965;5416.8104116355735;5451.781928722965;5526.640768350045;5533.861653458695;5562.388137304974;5400.729963501888;5406.540444062141;5416.972915135557;5420.810271640474;5452.482014208644;5399.190121481809;5492.148716948879;5552.33371995347;5562.749676264497;5576.525995290283;5537.224788529689;5578.748956296895;5589.77640894376;5594.524465420485;5602.85992850182 16:47:56 INFO - PROCESS | 14529 | |5;yui.html;736.8599966006459;758.3894964553535;771.2800454985382;813.9186081391858;846.9026062002868;15354.078755274677;15723;16371.59071023225;16512;16516.669666606675;16414.589635259126;16442.917785411057;16527.504174874743;16551.586210344718;16575.66848663026;646.0503060501056;742.5210739073308;755.395683453238;763.1605234242345;763.4121726270769;753.2655660730799;780.9336206422432;784.52928243054;785.1638005524127;785.5600863516421;786.2923368967918;794.4041968523608;799.2966189753009;801.9478733882279;804.2841870735022;669.8760729265101;781.620913856779;796.7370767646663;796.8207153390499;797.2187256488645;587.5270407322104;588.6144575303183;588.7350692188533;589.0927970924771;589.4223660812385;15049.774253386207;15164.696706065928;15175.696486070327;15195.62010949724;15227.086374817547;14241.145531268157;14432.353628986159;14515.491957781533;14533.418663253562;14600.488982885654;12428.316442595653;12444.377781111;12544.372781360895;12732.681682957907;13120.803187952188;4454.510003899569;4504.346869703907;4516.819327226907;4602.125596136757;4619.3763841881255;82271.588642057;82891.58554207251;83341.58329208376;83400;83832;10765;10871.728206794734;10932.453377331261;11391.373474458818;11747.588834390755;20562.69155962662;20940.58118837615;20951.16195352169;21016.264430745156;21198.78801211999;8481.703140390056;8645.481271123752;8671.739847804576;8695.826083478294;8886.822263554823;4708.599769019672;4832.903341933142;4869.829555965524;4876.366072410564;5001.174806157016;3379.2396710740377;3463.2380876207194;3542.628024057437;3546.929061418757;3572.4462708280257;656.2158220926043;657.5561495990188;658.1148355461896;661.1009027722293;669.8492839111161;716.9749058782918;748.4947660329365;761.482192109371;776.8446310737875;795.7572940253278;10042.347247428894;10246;10354.585816567404;10356.741081472872;10499.160067194605;624.1476452611522;724.8912663100577;756.1870988687074;756.9129550101809;762.4967521435826;4569.9314510282375;4807.975960120247;4878.975605121952;4899.363082799213;4962.60299176065;122757.38621306837;124653;125437;127186.36406818092;129107;105247;106099;106393.46803265934;106626.46686766672;110108.4494577522;56331;58232.708836455546;71471.6426417875;83440;85366.57316713501;9369.578368973276;9618.855717164388;9892.307538472238;10043.683382841225;10347.482625868826;36425;41075.383869242585;45571.316430254235;46801.29798052964;47069.293960591305;72405.6379718098;72692.63653681698;73003.63498182475;74655;75150;11106.555737770399;11488.770224595628;11538.192326537062;11802.645920622395;11807.173497855068;14261.714765704835;14402.135871847722;14832.332545035715;14903.403863845324;15126.773098403753;8514.957425212835;11807.881921180679;12503.475118153974;12975;13084.542041028519;12392.566260181211;12875.291858947849;12900;12976.935115324362;13111.40998655082;13853.584392468241;14029.929850350683;14285.785713214102;14566.635834104232;15144.318505667052;11202.607908723156;11558.595449159238;11886.881131188577;12045.096617753528;12075.818862717242;10721.147180573998;11173.329600224013;11211.943940280247;11263.605773797877;11297.43512824339 16:47:56 INFO - PROCESS | 14529 | __end_tp_report 16:47:56 INFO - PROCESS | 14529 | __start_cc_report 16:47:56 INFO - PROCESS | 14529 | _x_x_mozilla_cycle_collect,1065 16:47:56 INFO - PROCESS | 14529 | __end_cc_report 16:47:56 INFO - PROCESS | 14529 | __startTimestamp1465948076492__endTimestamp 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | ------- Summary: start ------- 16:47:56 INFO - PROCESS | 14529 | Number of tests: 6 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | [#0] dojo.html Cycles:165 Average:5407.67 Median:5587.39 stddev:626.10 (11.2%) stddev-sans-first:601.41 16:47:56 INFO - PROCESS | 14529 | Values: 3105.7 3119.9 3129.3 3155.2 3178.5 5294.9 5335.1 5373.6 5467.0 5479.0 5404.0 5421.1 5437.6 5449.4 5490.5 4852.8 4866.8 4928.1 4953.6 5138.7 5049.5 5066.1 5073.3 5112.4 5115.5 4801.3 4854.8 4902.3 4959.0 4961.3 4823.7 4831.2 4850.0 4909.2 4954.0 4890.9 4898.9 4899.0 4932.3 4961.1 5261.7 5332.4 5386.7 5402.5 5434.8 5841.3 5843.9 5863.2 5870.0 5874.7 5854.1 5894.2 5905.6 5918.5 5918.5 5281.2 5316.7 5317.5 5371.4 5405.1 6004.5 6008.7 6019.6 6026.8 6027.6 5724.3 5763.5 5774.9 5775.8 5789.9 5530.7 5727.9 5748.3 5838.5 5848.4 5755.9 5808.2 5836.3 5849.2 5861.5 5802.0 5804.1 5816.8 5818.8 5820.7 4332.9 4369.7 4432.7 4443.6 4473.2 3898.1 3925.7 3943.5 3953.1 3972.9 5523.9 5528.6 5530.4 5562.7 5562.8 5587.4 5813.0 5838.4 5860.4 5924.0 5492.0 5530.1 5543.5 5552.4 5604.9 5070.9 5077.9 5082.5 5087.8 5137.2 5749.6 5987.4 5994.6 6062.5 6095.0 5873.6 5879.3 5882.4 5884.4 5900.3 6038.6 6043.6 6054.7 6060.1 6061.8 5514.6 5662.4 5677.7 5700.0 5757.7 5868.3 5868.5 5869.4 5879.2 5880.8 5684.9 5736.6 5739.0 5741.3 5743.2 5450.5 5677.0 5696.1 5737.9 5768.5 5602.4 5621.3 5654.4 5660.1 5662.7 5583.9 5800.3 5855.4 5859.1 5863.2 5439.2 5487.5 5575.3 5587.2 5631.6 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | [#1] ext.html Cycles:180 Average:56133.07 Median:41349.09 stddev:106222.53 (256.9%) stddev-sans-first:106438.61 16:47:56 INFO - PROCESS | 14529 | Values: 576.7 616.2 636.0 639.5 649.1 40024.2 41442.2 41467.4 41496.0 44416.3 42745.1 42786.6 43039.1 43102.8 43148.8 8885.4 8993.6 9018.3 9021.8 9025.4 8878.1 8996.9 9005.4 9006.1 9006.6 9022.7 9056.8 9058.1 9062.3 9065.2 8927.5 8956.5 8986.9 8992.4 8994.6 8969.0 9044.5 9051.3 9052.6 9057.1 41376.8 41594.0 41639.4 41692.4 41776.0 3546.5 3570.4 3621.8 3723.5 3727.6 31692.5 31962.0 32171.7 32175.8 33496.0 4364.9 4366.7 4393.4 4495.6 4495.6 339352.0 339416.0 339724.0 340091.0 340335.0 56468.7 56556.4 56893.4 56950.7 56958.4 59911.7 60100.7 60196.1 60222.4 60274.7 14999.4 16214.3 16360.6 16366.1 16501.3 11454.9 11643.1 11643.1 11643.2 11646.7 17134.5 17265.9 17318.9 17322.9 17353.0 5696.7 6383.6 6461.3 6624.0 6860.4 915.3 978.6 1098.4 1136.0 1159.7 31394.2 31785.2 31853.5 32306.5 32524.8 1080.8 1134.9 1156.1 1194.9 1232.8 16138.1 16454.8 16719.3 16782.2 17018.6 514753.0 515141.0 515846.0 520287.0 523734.0 56881.1 56965.7 57037.0 57090.1 57106.0 1245.9 1299.3 1357.3 1362.4 1368.1 30822.7 32540.2 32730.7 32985.0 33282.3 48793.3 49088.8 49278.3 49285.8 49336.0 314783.0 316586.0 316653.0 317126.0 317237.4 38743.2 40550.0 41121.0 41208.2 44694.3 48176.5 48374.5 48910.8 49174.8 49245.8 39688.8 40734.4 41169.2 41448.8 42168.0 39347.2 39519.2 39633.4 40247.8 40486.4 40410.6 40435.0 40704.4 41238.2 41321.4 34257.8 35922.6 36030.3 36256.0 36427.3 27127.7 27593.7 27640.4 27692.2 28038.0 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | [#2] jquery.html Cycles:180 Average:29070.40 Median:23382.77 stddev:81409.75 (348.2%) stddev-sans-first:81609.61 16:47:56 INFO - PROCESS | 14529 | Values: 373.5 375.2 390.9 397.4 397.6 15290.2 16657.4 17635.2 19905.2 20088.2 18004.5 18647.3 19232.0 19281.5 19294.5 5387.0 5444.3 5645.0 5675.5 5680.7 4070.1 4277.0 4288.9 4329.7 5508.6 4303.7 5875.8 6134.6 6199.9 6306.6 6006.6 6182.1 6195.8 6198.8 6253.7 5415.8 5457.2 5463.6 5468.0 5470.8 21256.0 21698.3 21758.1 22506.4 23458.1 2491.1 2588.6 2820.7 3097.1 3119.8 13691.5 14225.9 14436.9 14971.3 15054.6 1845.5 1895.7 1908.4 1952.1 1958.7 68915.3 78369.6 85170.6 86951.0 88422.0 9754.3 9776.4 9894.4 9923.7 9950.7 13301.2 13386.5 13682.0 13761.7 13796.3 7772.1 7969.7 7999.8 8014.0 8165.7 8066.5 8110.6 8212.3 8271.5 8303.3 4648.9 4654.4 4682.4 4730.8 4819.1 1684.2 1748.2 1750.6 1773.7 1790.7 775.4 947.9 1012.3 1030.0 1059.2 18561.0 19161.5 19341.0 19530.5 19730.0 920.4 938.4 973.1 986.0 1019.0 10623.2 11119.6 11132.3 11146.7 11162.9 490636.0 490996.0 493351.0 496025.0 496057.0 91153.5 92672.5 93186.5 93845.1 94456.5 958.3 964.5 965.2 996.1 1009.7 17151.7 17227.4 17478.7 17480.3 17591.7 42145.4 42357.8 42488.6 42850.0 43044.1 57032.4 58442.4 58909.4 59253.7 59638.6 8369.5 8551.1 8551.7 8711.6 8893.1 9596.0 13251.7 13382.8 13462.8 13829.7 8152.9 11374.5 11377.4 11577.9 11717.9 11621.2 11773.1 11924.5 11947.5 12017.0 6624.6 10975.5 11863.2 12202.0 12297.6 7070.9 7685.9 7916.8 8423.2 8545.0 10242.7 12068.7 12209.3 12378.6 12380.4 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | [#3] mootools.html Cycles:180 Average:5730.58 Median:4699.89 stddev:3283.71 (69.9%) stddev-sans-first:3275.93 16:47:56 INFO - PROCESS | 14529 | Values: 1286.1 1427.4 1464.6 1500.7 1701.5 8170.8 8324.4 8347.3 8783.5 8800.7 8511.1 8674.9 8706.6 8707.6 8721.8 2001.5 2217.3 2274.1 2284.7 2316.5 2353.2 2361.9 2363.6 2364.2 2365.8 2281.1 2295.2 2298.0 2303.1 2319.0 1945.1 2178.9 2211.2 2214.1 2230.8 2259.9 2280.1 2300.6 2300.7 2306.2 8738.7 8758.6 8807.7 8808.2 8834.2 5102.9 5247.6 5250.5 5253.8 5254.7 3136.7 3263.9 3295.2 3314.7 3315.8 1094.5 1130.6 1138.1 1153.9 1167.7 11978.8 12004.7 12050.0 12053.6 12054.9 4436.6 4468.1 4561.5 4591.7 4602.6 10276.4 10302.6 10308.6 10310.7 10311.7 5124.2 5126.7 5128.6 5133.7 5134.6 4302.8 4653.5 4746.3 4753.8 4880.7 3864.9 3890.5 3917.2 4010.8 4053.9 1207.8 1230.4 1232.8 1233.0 1241.1 923.8 944.7 958.1 960.4 991.7 5854.3 5976.8 6066.8 6084.0 6168.4 440.7 443.0 450.6 454.3 459.5 3811.2 3967.2 3977.2 3982.7 3999.0 10250.6 10668.3 10718.6 10749.9 10788.6 10587.3 10720.9 10731.0 10734.4 10896.0 8031.2 8072.4 8125.5 8164.7 8171.5 5903.8 5921.0 5968.8 6044.2 6174.4 9407.2 9929.2 9932.0 9938.9 10037.3 9618.9 9906.0 10056.3 10100.0 10110.7 7006.6 7443.6 7480.2 7708.5 7909.7 8593.2 8597.1 8687.9 8741.0 8742.7 7590.2 7819.6 7842.5 8058.8 8098.5 7675.2 7715.2 7800.5 7844.3 7931.0 7164.5 7182.5 7287.7 7315.3 7394.5 4847.4 4913.5 4983.6 5062.3 5096.6 5218.2 5315.0 5337.5 5347.3 5374.5 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | [#4] prototype.html Cycles:180 Average:4646.51 Median:5313.96 stddev:1371.58 (25.8%) stddev-sans-first:1336.33 16:47:56 INFO - PROCESS | 14529 | Values: 314.4 322.1 330.6 331.5 333.8 5281.3 5402.6 5437.9 5465.5 5546.8 5346.6 5370.6 5387.5 5411.6 5419.3 4139.2 4173.3 4180.2 4180.9 4197.9 3912.7 4121.4 4140.1 4185.4 4191.9 4059.6 4090.4 4100.3 4102.9 4103.2 4099.2 4115.2 4124.6 4136.4 4156.4 3618.7 3632.7 3638.9 3640.4 3655.3 5084.7 5185.8 5186.5 5191.0 5196.7 4708.9 4719.2 4722.9 4723.6 4723.7 5438.4 5522.6 5543.1 5547.4 5547.6 3877.5 3958.2 3959.5 3970.4 3986.7 5529.8 5581.3 5696.8 5719.5 5744.5 4009.7 4022.6 4029.2 4077.6 4084.4 3929.3 4011.5 4104.1 4137.9 4146.4 4645.9 4676.6 4730.3 4777.5 4781.9 5521.7 5535.2 5553.9 5606.0 5627.4 1357.5 1434.2 1435.0 1435.5 1437.2 890.6 905.3 917.9 919.9 929.0 4823.8 4838.2 4845.4 4850.2 4858.7 5115.4 5161.4 5179.9 5181.5 5186.8 4684.8 4746.7 4772.6 4787.3 5101.3 3011.2 3037.6 3055.8 3072.9 3080.4 5860.0 5876.9 5896.0 5934.2 5969.2 5621.6 5912.4 5936.9 5938.1 5953.6 5942.2 5942.5 5978.8 5984.9 5993.8 5365.4 5480.3 5767.7 5777.5 5792.2 5702.5 5726.3 5731.7 5735.2 5747.8 5917.3 5946.7 5953.0 5956.5 5984.3 3838.9 4074.1 4077.8 4083.8 4092.2 5585.7 5645.0 5670.2 5681.6 5683.3 5564.3 5574.7 5594.4 5621.6 5655.5 5416.8 5451.8 5526.6 5533.9 5562.4 5400.7 5406.5 5417.0 5420.8 5452.5 5399.2 5492.1 5552.3 5562.7 5576.5 5537.2 5578.7 5589.8 5594.5 5602.9 16:47:56 INFO - PROCESS | 14529 | 16:47:56 INFO - PROCESS | 14529 | [#5] yui.html Cycles:180 Average:20890.52 Median:19998.72 stddev:30886.03 (154.4%) stddev-sans-first:30935.60 16:47:56 INFO - PROCESS | 14529 | Values: 736.9 758.4 771.3 813.9 846.9 15354.1 15723.0 16371.6 16512.0 16516.7 16414.6 16442.9 16527.5 16551.6 16575.7 646.1 742.5 755.4 763.2 763.4 753.3 780.9 784.5 785.2 785.6 786.3 794.4 799.3 801.9 804.3 669.9 781.6 796.7 796.8 797.2 587.5 588.6 588.7 589.1 589.4 15049.8 15164.7 15175.7 15195.6 15227.1 14241.1 14432.4 14515.5 14533.4 14600.5 12428.3 12444.4 12544.4 12732.7 13120.8 4454.5 4504.3 4516.8 4602.1 4619.4 82271.6 82891.6 83341.6 83400.0 83832.0 10765.0 10871.7 10932.5 11391.4 11747.6 20562.7 20940.6 20951.2 21016.3 21198.8 8481.7 8645.5 8671.7 8695.8 8886.8 4708.6 4832.9 4869.8 4876.4 5001.2 3379.2 3463.2 3542.6 3546.9 3572.4 656.2 657.6 658.1 661.1 669.8 717.0 748.5 761.5 776.8 795.8 10042.3 10246.0 10354.6 10356.7 10499.2 624.1 724.9 756.2 756.9 762.5 4569.9 4808.0 4879.0 4899.4 4962.6 122757.4 124653.0 125437.0 127186.4 129107.0 105247.0 106099.0 106393.5 106626.5 110108.4 56331.0 58232.7 71471.6 83440.0 85366.6 9369.6 9618.9 9892.3 10043.7 10347.5 36425.0 41075.4 45571.3 46801.3 47069.3 72405.6 72692.6 73003.6 74655.0 75150.0 11106.6 11488.8 11538.2 11802.6 11807.2 14261.7 14402.1 14832.3 14903.4 15126.8 8515.0 11807.9 12503.5 12975.0 13084.5 12392.6 12875.3 12900.0 12976.9 13111.4 13853.6 14029.9 14285.8 14566.6 15144.3 11202.6 11558.6 11886.9 12045.1 12075.8 10721.1 11173.3 11211.9 11263.6 11297.4 16:47:56 INFO - PROCESS | 14529 | -------- Summary: end -------- 16:47:56 INFO - PROCESS | 14529 | 16:47:57 INFO - TEST-INFO | 14529: exit 0 16:47:57 INFO - TEST-OK | dromaeo_css | took 1114459ms 16:47:57 INFO - TEST-START | kraken 16:47:57 INFO - operating with platform_type : linux_ 16:47:57 INFO - Initialising browser for kraken test... 16:47:57 INFO - TEST-INFO | started process 14706 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0UI7UC/profile http://localhost:44462/getInfo.html) 16:48:01 INFO - PROCESS | 14706 | __metrics Screen width/height:1600/1200 16:48:01 INFO - PROCESS | 14706 | colorDepth:24 16:48:01 INFO - PROCESS | 14706 | Browser inner width/height: 1024/697 16:48:01 INFO - PROCESS | 14706 | __metrics 16:48:02 INFO - PROCESS | 14706 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:48:02 INFO - PROCESS | 14706 | console.error: PushService: 16:48:02 INFO - PROCESS | 14706 | stateChangeProcessEnqueue: Error transitioning state 16:48:02 INFO - PROCESS | 14706 | UnknownError 16:48:03 INFO - TEST-INFO | 14706: exit 0 16:48:03 INFO - Browser initialized. 16:48:03 INFO - Running cycle 1/1 for kraken test... 16:48:03 INFO - TEST-INFO | started process 14785 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0UI7UC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:15 INFO - PROCESS | 14785 | RSS: Main: 174362624 16:48:15 INFO - PROCESS | 14785 | 16:48:17 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44462/tests/kraken/driver.html?testName=audio-beat-detection) 16:48:18 INFO - PROCESS | 14785 | RSS: Main: 232427520 16:48:18 INFO - PROCESS | 14785 | 16:48:21 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44462/tests/kraken/driver.html?testName=audio-dft) 16:48:21 INFO - PROCESS | 14785 | RSS: Main: 221908992 16:48:21 INFO - PROCESS | 14785 | 16:48:25 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44462/tests/kraken/driver.html?testName=audio-fft) 16:48:25 INFO - PROCESS | 14785 | RSS: Main: 287199232 16:48:25 INFO - PROCESS | 14785 | 16:48:27 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44462/tests/kraken/driver.html?testName=audio-oscillator) 16:48:28 INFO - PROCESS | 14785 | RSS: Main: 198193152 16:48:28 INFO - PROCESS | 14785 | 16:48:30 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44462/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:48:31 INFO - PROCESS | 14785 | RSS: Main: 202153984 16:48:31 INFO - PROCESS | 14785 | 16:48:34 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44462/tests/kraken/driver.html?testName=imaging-darkroom) 16:48:34 INFO - PROCESS | 14785 | RSS: Main: 237535232 16:48:34 INFO - PROCESS | 14785 | 16:48:38 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44462/tests/kraken/driver.html?testName=imaging-desaturate) 16:48:38 INFO - PROCESS | 14785 | RSS: Main: 238739456 16:48:38 INFO - PROCESS | 14785 | 16:48:41 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44462/tests/kraken/driver.html?testName=json-parse-financial) 16:48:42 INFO - PROCESS | 14785 | RSS: Main: 238391296 16:48:42 INFO - PROCESS | 14785 | 16:48:43 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44462/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:48:44 INFO - PROCESS | 14785 | RSS: Main: 225837056 16:48:44 INFO - PROCESS | 14785 | 16:48:46 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:48:46 INFO - PROCESS | 14785 | RSS: Main: 230596608 16:48:46 INFO - PROCESS | 14785 | 16:48:48 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:48:49 INFO - PROCESS | 14785 | RSS: Main: 239104000 16:48:49 INFO - PROCESS | 14785 | 16:48:51 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:48:52 INFO - PROCESS | 14785 | RSS: Main: 242475008 16:48:52 INFO - PROCESS | 14785 | 16:48:55 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:48:55 INFO - PROCESS | 14785 | RSS: Main: 242200576 16:48:55 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | Cycle 1(1): loaded http://localhost:44462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44462/tests/kraken/driver.html?testName=ai-astar) 16:48:57 INFO - PROCESS | 14785 | RSS: Main: 284971008 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | __start_tp_report 16:48:57 INFO - PROCESS | 14785 | _x_x_mozilla_page_load 16:48:57 INFO - PROCESS | 14785 | _x_x_mozilla_page_load_details 16:48:57 INFO - PROCESS | 14785 | |i|pagename|runs| 16:48:57 INFO - PROCESS | 14785 | |0;ai-astar;112;106;110;109;110;110;110;112;111;111 16:48:57 INFO - PROCESS | 14785 | |1;audio-beat-detection;166;127;133;195;127;125;149;123;153;125 16:48:57 INFO - PROCESS | 14785 | |2;audio-dft;160;161;159;162;161;161;159;161;161;161 16:48:57 INFO - PROCESS | 14785 | |3;audio-fft;73;75;75;126;75;78;74;74;103;74 16:48:57 INFO - PROCESS | 14785 | |4;audio-oscillator;85;85;114;84;85;86;114;84;85;128 16:48:57 INFO - PROCESS | 14785 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;118 16:48:57 INFO - PROCESS | 14785 | |6;imaging-darkroom;147;146;146;148;148;146;147;147;148;148 16:48:57 INFO - PROCESS | 14785 | |7;imaging-desaturate;95;94;94;95;96;95;95;95;95;95 16:48:57 INFO - PROCESS | 14785 | |8;json-parse-financial;55;54;55;57;54;62;54;55;53;54 16:48:57 INFO - PROCESS | 14785 | |9;json-stringify-tinderbox;61;61;61;60;61;60;61;60;60;61 16:48:57 INFO - PROCESS | 14785 | |10;stanford-crypto-aes;79;79;78;78;79;80;81;86;107;85 16:48:57 INFO - PROCESS | 14785 | |11;stanford-crypto-ccm;123;125;124;122;129;121;118;134;154;131 16:48:57 INFO - PROCESS | 14785 | |12;stanford-crypto-pbkdf2;166;163;159;158;161;164;161;159;158;160 16:48:57 INFO - PROCESS | 14785 | |13;stanford-crypto-sha256-iterative;69;62;63;62;66;63;62;64;62;62 16:48:57 INFO - PROCESS | 14785 | __end_tp_report 16:48:57 INFO - PROCESS | 14785 | __start_cc_report 16:48:57 INFO - PROCESS | 14785 | _x_x_mozilla_cycle_collect,1628 16:48:57 INFO - PROCESS | 14785 | __end_cc_report 16:48:57 INFO - PROCESS | 14785 | __startTimestamp1465948137808__endTimestamp 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | ------- Summary: start ------- 16:48:57 INFO - PROCESS | 14785 | Number of tests: 14 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.50 stddev:1.73 (1.6%) stddev-sans-first:1.69 16:48:57 INFO - PROCESS | 14785 | Values: 112.0 106.0 110.0 109.0 110.0 110.0 110.0 112.0 111.0 111.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:141.00 stddev:23.63 (16.8%) stddev-sans-first:23.45 16:48:57 INFO - PROCESS | 14785 | Values: 166.0 127.0 133.0 195.0 127.0 125.0 149.0 123.0 153.0 125.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 16:48:57 INFO - PROCESS | 14785 | Values: 160.0 161.0 159.0 162.0 161.0 161.0 159.0 161.0 161.0 161.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#3] audio-fft Cycles:10 Average:82.70 Median:74.50 stddev:17.66 (23.7%) stddev-sans-first:18.38 16:48:57 INFO - PROCESS | 14785 | Values: 73.0 75.0 75.0 126.0 75.0 78.0 74.0 74.0 103.0 74.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:85.00 stddev:16.78 (19.7%) stddev-sans-first:17.40 16:48:57 INFO - PROCESS | 14785 | Values: 85.0 85.0 114.0 84.0 85.0 86.0 114.0 84.0 85.0 128.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:48:57 INFO - PROCESS | 14785 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.50 stddev:0.88 (0.6%) stddev-sans-first:0.93 16:48:57 INFO - PROCESS | 14785 | Values: 147.0 146.0 146.0 148.0 148.0 146.0 147.0 147.0 148.0 148.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:48:57 INFO - PROCESS | 14785 | Values: 95.0 94.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:2.58 (4.7%) stddev-sans-first:2.74 16:48:57 INFO - PROCESS | 14785 | Values: 55.0 54.0 55.0 57.0 54.0 62.0 54.0 55.0 53.0 54.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 16:48:57 INFO - PROCESS | 14785 | Values: 61.0 61.0 61.0 60.0 61.0 60.0 61.0 60.0 60.0 61.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:79.50 stddev:8.82 (11.1%) stddev-sans-first:9.22 16:48:57 INFO - PROCESS | 14785 | Values: 79.0 79.0 78.0 78.0 79.0 80.0 81.0 86.0 107.0 85.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:127.00 stddev:10.31 (8.1%) stddev-sans-first:10.77 16:48:57 INFO - PROCESS | 14785 | Values: 123.0 125.0 124.0 122.0 129.0 121.0 118.0 134.0 154.0 131.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.00 stddev:2.69 (1.7%) stddev-sans-first:2.12 16:48:57 INFO - PROCESS | 14785 | Values: 166.0 163.0 159.0 158.0 161.0 164.0 161.0 159.0 158.0 160.0 16:48:57 INFO - PROCESS | 14785 | 16:48:57 INFO - PROCESS | 14785 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:2.32 (3.7%) stddev-sans-first:1.36 16:48:57 INFO - PROCESS | 14785 | Values: 69.0 62.0 63.0 62.0 66.0 63.0 62.0 64.0 62.0 62.0 16:48:57 INFO - PROCESS | 14785 | -------- Summary: end -------- 16:48:57 INFO - PROCESS | 14785 | 16:48:58 INFO - TEST-INFO | 14785: exit 0 16:48:58 INFO - TEST-OK | kraken | took 61521ms 16:48:58 INFO - SUITE-END | took 1175s 16:48:58 INFO - Completed test suite (00:19:36) 16:48:58 INFO - javascript benchmark 16:48:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.658377578466, 3119.937601247974, 3129.295908420605, 3155.2427417419826, 3178.459661857484, 5294.9470505294985, 5335.146376579748, 5373.570114390849, 5467.043267428199, 5478.958997790417, 5404.0, 5421.051316019701, 5437.564994800407, 5449.373322067963, 5490.450954904508, 4852.757362131888, 4866.805327786884, 4928.063667903087, 4953.603711703073, 5138.743062846861, 5049.520295571915, 5066.138756411419, 5073.315102461176, 5112.437631860493, 5115.488451154892, 4801.279808028806, 4854.781534830906, 4902.289168070645, 4959.0, 4961.255811628249, 4823.734694591796, 4831.202851529493, 4850.029994001214, 4909.239067944439, 4954.0, 4890.902181956341, 4898.946726453794, 4899.044686286175, 4932.260160975882, 4961.131801934647, 5261.684298942076, 5332.360116786011, 5386.676799392049, 5402.540784033362, 5434.83695489136, 5841.269841269841, 5843.941560584425, 5863.237779088691, 5870.0, 5874.706264686749, 5854.092615644539, 5894.17481552583, 5905.645661260338, 5918.467337939563, 5918.467337939563, 5281.15501519755, 5316.73416329182, 5317.494837990384, 5371.355437347544, 5405.054115529806, 6004.489618382487, 6008.669523176267, 6019.578629495893, 6026.819195424145, 6027.60820546663, 5724.255846739898, 5763.538916886639, 5774.855628609234, 5775.797847075332, 5789.88420231593, 5530.695811730313, 5727.914081288784, 5748.310202775694, 5838.5329173666005, 5848.4151584841175, 5755.884882302331, 5808.244928159313, 5836.299644042657, 5849.181114643953, 5861.472467477947, 5801.970990145107, 5804.100364443518, 5816.825495235065, 5818.8254352369495, 5820.679862607512, 4332.913341733148, 4369.737815731066, 4432.711873728197, 4443.644508439317, 4473.217186992263, 3898.1229223424502, 3925.7055720821054, 3943.4676318697184, 3953.0710283083454, 3972.940405893914, 5523.861903452445, 5528.55771538276, 5530.446955304437, 5562.72186390687, 5562.7774889004795, 5587.38538760736, 5813.040848260074, 5838.4453476919625, 5860.355360910296, 5923.970380148072, 5492.0, 5530.087535556588, 5543.52880005204, 5552.3892371839065, 5604.943950560442, 5070.873228169252, 5077.873053173626, 5082.542571168538, 5087.821926232564, 5137.152369859006, 5749.626274292157, 5987.401259873977, 5994.580379373402, 6062.454379105901, 6095.024796032613, 5873.618214816023, 5879.2944846618675, 5882.441168089196, 5884.382139875252, 5900.262467191601, 6038.607490513278, 6043.637381757108, 6054.727537260922, 6060.090986351906, 6061.848453788514, 5514.61397702157, 5662.405447427961, 5677.744501497526, 5699.974004679198, 5757.740901659519, 5868.2958045033165, 5868.501177399969, 5869.383714709894, 5879.20630714848, 5880.8235752927485, 5684.914726279192, 5736.569757268138, 5739.0, 5741.282339707536, 5743.167240750151, 5450.509454149145, 5677.0, 5696.088625819849, 5737.8565535861935, 5768.480836724715, 5602.411746766531, 5621.269234999424, 5654.434556544313, 5660.066089095418, 5662.660240385425, 5583.860403489945, 5800.332961709458, 5855.443732845373, 5859.121131830089, 5863.208466857091, 5439.238506609157, 5487.451254874481, 5575.330960284792, 5587.161925711176, 5631.577631677558], "value": 5364.677416063421, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.708762075152, 616.2050954268993, 636.0491065856545, 639.4628512049877, 649.0815496073056, 40024.19951600966, 41442.17115657685, 41467.37798933015, 41496.0, 44416.3337549937, 42745.14509709812, 42786.57213427873, 43039.139217215634, 43102.7844860776, 43148.784256078674, 8885.422447540905, 8993.550322483883, 9018.278537716968, 9021.819563608724, 9025.368224224307, 8878.067802880701, 8996.910030899673, 9005.414648047888, 9006.099390061006, 9006.639734410648, 9022.684206052789, 9056.773580660503, 9058.094190580921, 9062.275017998578, 9065.22945549629, 8927.46435213886, 8956.507392093432, 8986.865197022082, 8992.370534062618, 8994.550272486415, 8968.955155224246, 9044.457332560001, 9051.321150913642, 9052.59263333145, 9057.094290570956, 41376.793116034525, 41594.0, 41639.375409368884, 41692.37461438081, 41776.0, 3546.4680297955256, 3570.428731402963, 3621.8189090545366, 3723.5345581802276, 3727.552693676749, 31692.524612130837, 31962.0, 32171.6782832171, 32175.83912080448, 33496.0, 4364.869053928387, 4366.672499562545, 4393.384926110347, 4495.550444955478, 4495.64034877209, 339352.0, 339416.0, 339724.0, 340091.0, 340335.0, 56468.717656411456, 56556.434435655116, 56893.43106568964, 56950.715246423504, 56958.430415695315, 59911.700441497516, 60100.69949650224, 60196.097058544154, 60222.39777602255, 60274.698626506586, 14999.400023999136, 16214.27035783369, 16360.590985225464, 16366.099864507567, 16501.33994640225, 11454.94272528632, 11643.06855451562, 11643.126765492621, 11643.243189192675, 11646.650600481998, 17134.485965421056, 17265.913670431568, 17318.913405432893, 17322.913385432992, 17353.0, 5696.715164241771, 6383.553151279366, 6461.2892581816905, 6624.0, 6860.382565569123, 915.2769312243254, 978.554757585297, 1098.3629494892944, 1135.968355877118, 1159.686884541169, 31394.21514462157, 31785.205369865474, 31853.52219716706, 32306.515402268986, 32524.837375813444, 1080.8000519903842, 1134.8581427321585, 1156.0982433701727, 1194.9283043017447, 1232.8335674683983, 16138.112403817902, 16454.83545164557, 16719.331226750794, 16782.160891955355, 17018.574535636708, 514753.0, 515141.0, 515846.0, 520287.0, 523734.0, 56881.14678279829, 56965.71517142388, 57037.0, 57090.14364784532, 57106.00386959392, 1245.850497940253, 1299.259422129377, 1357.314556149158, 1362.3869258833565, 1368.0560413314781, 30822.69177308198, 32540.186495337806, 32730.67269327276, 32985.0, 33282.33435331328, 48793.2681009778, 49088.75455622699, 49278.260826086924, 49285.753571231915, 49336.0, 314783.0, 316586.0, 316653.0, 317126.0, 317237.44862962444, 38743.2251354977, 40549.98625034398, 41121.0, 41208.1758364837, 44694.3295850556, 48176.518234818606, 48374.516254837, 48910.755446223964, 49174.75412622914, 49245.75377123091, 39688.80155599319, 40734.38898416585, 41169.1766164681, 41448.792756036026, 42168.0, 39347.21305573815, 39519.2096158081, 39633.40549891696, 40247.79876100601, 40486.392704110054, 40410.595894040685, 40435.0, 40704.3894341591, 41238.1752364945, 41321.380179296735, 34257.828710857284, 35922.64077359193, 36030.279394412486, 36256.0, 36427.27145457129, 27127.72872271252, 27593.724062759116, 27640.447191055664, 27692.169234922985, 28038.0], "value": 19916.53006719811, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.54054512949074, 375.1729079383403, 390.8592906553641, 397.40786228519516, 397.58055251709453, 15290.159041252755, 16657.416990405338, 17635.20641571129, 19905.203791848326, 20088.196472141117, 18004.459866204, 18647.347342843004, 19232.0, 19281.517962050955, 19294.517637059045, 5387.0, 5444.3194600674915, 5645.0, 5675.489205971462, 5680.6591604503865, 4070.14526949341, 4276.957230427703, 4288.892777680552, 4329.718568293051, 5508.614396992212, 4303.720258183223, 5875.764969401219, 6134.570580059375, 6199.876002479948, 6306.558540902139, 6006.609570377911, 6182.103594978703, 6195.783147589857, 6198.752049917998, 6253.7185826637915, 5415.783368665248, 5457.2359869618285, 5463.562914966793, 5468.0, 5470.781168753245, 21256.0, 21698.34904952854, 21758.12967481299, 22506.437339066655, 23458.061677532878, 2491.078301028631, 2588.59876719107, 2820.6615206175184, 3097.117321563366, 3119.7972131811584, 13691.452341906312, 14225.857741422557, 14436.855631443761, 14971.251437428195, 15054.623634409118, 1845.455590600776, 1895.6966885298425, 1908.3702378215155, 1952.1410579345184, 1958.7257783910263, 68915.3108468919, 78369.60815195944, 85170.57414712886, 86951.0, 88422.0, 9754.317197796086, 9776.364536305118, 9894.356866803635, 9923.7022889312, 9950.65172718966, 13301.201927884164, 13386.464541418429, 13682.042257041912, 13761.724765504634, 13796.310184490736, 7772.145064042951, 7969.681212751541, 7999.800004999922, 8013.959930200312, 8165.665851187364, 8066.467371199454, 8110.6211943969665, 8212.260896519223, 8271.462354946909, 8303.252707256375, 4648.883777905512, 4654.37165982588, 4682.367880336179, 4730.763461826895, 4819.108464933998, 1684.1831711619855, 1748.1783561725968, 1750.6016757042678, 1773.7073382891936, 1790.7492950986873, 775.3603277296253, 947.9289053321029, 1012.3116280928891, 1029.9794004119876, 1059.2161800267747, 18561.0, 19161.52096197606, 19341.0, 19530.511737206398, 19730.0, 920.397139873384, 938.4134915677702, 973.1047436358567, 985.9507024648739, 1018.9796204076025, 10623.20325975555, 11119.610813621646, 11132.276402033842, 11146.721331966766, 11162.944185279022, 490636.0, 490996.0, 493351.0, 496025.0, 496057.0, 91153.54423227841, 92672.53663731772, 93186.53406733059, 93845.08471695178, 94456.52771736235, 958.3339578992533, 964.5418426247617, 965.2374624047064, 996.1151509114511, 1009.6769033909079, 17151.656966860843, 17227.3970411035, 17478.650426991135, 17480.30078796834, 17591.736123957893, 42145.36781948212, 42357.788211058745, 42488.57511424846, 42850.0, 43044.13911721685, 57032.44126925457, 58442.4155758437, 58909.41090589039, 59253.703731481066, 59638.614455421295, 8369.454487345867, 8551.144885511649, 8551.657933682583, 8711.606143017087, 8893.110688931056, 9595.952020239854, 13251.734965300448, 13382.799258010942, 13462.844789543899, 13829.723405532035, 8152.877706834521, 11374.488148033524, 11377.374244416636, 11577.884221157681, 11717.882821172021, 11621.186516943733, 11773.117016223647, 11924.463399147233, 11947.522099115939, 12017.038636909201, 6624.6025238485845, 10975.50610222558, 11863.169578129447, 12202.0, 12297.56958506448, 7070.858582828418, 7685.884711729441, 7916.84166316659, 8423.199796019368, 8545.0, 10242.743931401774, 12068.698282542655, 12209.328486933304, 12378.566750164062, 12380.380980951097], "value": 9117.75075283012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.0740267007752, 1427.3514091224633, 1464.6118778523694, 1500.7148641758065, 1701.455534229047, 8170.8365832683385, 8324.375671824615, 8347.290480309173, 8783.472991620507, 8800.691975780848, 8511.148885111486, 8674.91325086749, 8706.608202630881, 8707.564621768903, 8721.825563488743, 2001.5396458814482, 2217.2904670505445, 2274.101729816721, 2284.7486776454616, 2316.5019520803025, 2353.235198560466, 2361.8819059047064, 2363.61000434928, 2364.243442098529, 2365.8462199957034, 2281.064763446987, 2295.150794206146, 2298.0463107810237, 2303.09027933966, 2318.9652155217686, 1945.085809669453, 2178.9128434862587, 2211.159759291467, 2214.1143542582936, 2230.8326875484404, 2259.8644081355174, 2280.1335492512903, 2300.620397634388, 2300.7124109486313, 2306.1928325086255, 8738.694145704934, 8758.605862736193, 8807.691730789456, 8808.163224493663, 8834.204921557026, 5102.9489705103215, 5247.606429517801, 5250.527452529291, 5253.7635806388425, 5254.737263136866, 3136.6863313668678, 3263.885763998249, 3295.1597342677705, 3314.7182489488423, 3315.751318651111, 1094.5402930769096, 1130.5703832543663, 1138.1179585820971, 1153.9076873850156, 1167.6788883057143, 11978.820317695241, 12004.69988250292, 12050.0, 12053.57812476559, 12054.939725301492, 4436.64506839452, 4468.123388176973, 4561.498235194126, 4591.655625828076, 4602.631789456836, 10276.383416995004, 10302.639407620698, 10308.639197628047, 10310.690679279634, 10311.742206444898, 5124.231365295235, 5126.666766660155, 5128.640995130305, 5133.743312834344, 5134.5635620972635, 4302.752201861487, 4653.488116307203, 4746.288056791509, 4753.809847606057, 4880.658353915192, 3864.8840534784003, 3890.533136023695, 3917.216556688674, 4010.7593544387432, 4053.8986525336513, 1207.8007128823647, 1230.3540641163281, 1232.7534493101236, 1232.975340493185, 1241.075398827861, 923.7736754495191, 944.7401964459829, 958.0993865766158, 960.4045491794992, 991.682661548312, 5854.2974843018255, 5976.760929562855, 6066.817995460143, 6084.0, 6168.383161683796, 440.7245471580262, 442.99557004430187, 450.62372918613323, 454.332131766303, 459.4807867110211, 3811.19964807388, 3967.206558688274, 3977.204559088194, 3982.6614737747036, 3999.0, 10250.58997640086, 10668.30656007357, 10718.571257149782, 10749.892501075046, 10788.622398216025, 10587.258891877495, 10720.892791071989, 10731.0, 10734.355938643706, 10896.0, 8031.196880312039, 8072.4349295548745, 8125.512469251864, 8164.714235001747, 8171.468854524437, 5903.763849445974, 5921.0, 5968.820935371946, 6044.15381846533, 6174.38256174379, 9407.153356197701, 9929.156021737945, 9932.0, 9938.850917236103, 10037.34757240777, 9618.941916389052, 9906.0, 10056.29605927578, 10100.040496152853, 10110.69667909964, 7006.563654450927, 7443.590602516913, 7480.177180510031, 7708.537487750753, 7909.683612655429, 8593.226609604915, 8597.140285971353, 8687.91312086871, 8740.956295218482, 8742.694005709769, 7590.1650818408825, 7819.648115834687, 7842.451028427955, 8058.83882322362, 8098.51408915467, 7675.194104618935, 7715.22847715224, 7800.45396822217, 7844.254795794391, 7931.0, 7164.534305270141, 7182.497225194186, 7287.708491660274, 7315.268473152855, 7394.519356241827, 4847.442544107332, 4913.508649135058, 4983.576396006235, 5062.316587260672, 5096.643234973664, 5218.24335471347, 5315.04329220744, 5337.492938170868, 5347.304850369583, 5374.48942350476], "value": 4492.774792686354, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.4371574880962, 322.1286420233269, 330.58511567982174, 331.48123187212025, 333.75969302102493, 5281.339832520935, 5402.621816472849, 5437.891242175159, 5465.453454654533, 5546.779708464142, 5346.583155463795, 5370.597205209615, 5387.49150237934, 5411.594130440213, 5419.268398766166, 4139.234241665286, 4173.332266837307, 4180.226658068252, 4180.9338618652355, 4197.8530751423705, 3912.7065470089715, 4121.423000779893, 4140.130572579762, 4185.434966279544, 4191.853285135021, 4059.5534491205945, 4090.386442033689, 4100.261952848486, 4102.938455923164, 4103.1793641271715, 4099.1801639672185, 4115.238680844039, 4124.566920473367, 4136.4415803866395, 4156.355764856422, 3618.6743193112484, 3632.7093832493338, 3638.9272214555826, 3640.4357324614725, 3655.250673611903, 5084.7457627118865, 5185.818496352609, 5186.455422180655, 5191.0, 5196.740162991835, 4708.929366059512, 4719.150552900476, 4722.929156062662, 4723.574878260939, 4723.740194289313, 5438.402869474391, 5522.585806064561, 5543.1408131739645, 5547.44525547446, 5547.583931205136, 3877.476540667002, 3958.2281455116263, 3959.544652364986, 3970.3647416413237, 3986.7009974252046, 5529.806456773994, 5581.30233720789, 5696.829095127153, 5719.485246327808, 5744.4829965303325, 4009.67922566194, 4022.577629348935, 4029.2143032108456, 4077.55146933837, 4084.4281800547947, 3929.25344184604, 4011.4584531088512, 4104.056067104582, 4137.8965525862095, 4146.4402305688345, 4645.907081858344, 4676.649251306166, 4730.314104454835, 4777.474477807438, 4781.856544303677, 5521.723913804293, 5535.197396377503, 5553.888922221533, 5605.990921634064, 5627.352854421712, 1357.5376462096726, 1434.2255182201702, 1435.0, 1435.454527279627, 1437.1664434628099, 890.6214858685033, 905.307439808547, 917.8714979902819, 919.8988111307751, 928.9581968811419, 4823.831165909247, 4838.225883858566, 4845.394325709286, 4850.248211527219, 4858.708477491291, 5115.437301896863, 5161.4322424533275, 5179.948200517946, 5181.549166233461, 5186.766595503212, 4684.8360307389075, 4746.677325871903, 4772.5943294819635, 4787.257975013878, 5101.311322971352, 3011.2020314616643, 3037.559553864677, 3055.7860949733304, 3072.86304067492, 3080.430120427683, 5859.970700146558, 5876.882462350729, 5896.0, 5934.19888315072, 5969.239074473041, 5621.550275977912, 5912.438318359749, 5936.871994321152, 5938.079597662369, 5953.553483488756, 5942.227510423617, 5942.524598032147, 5978.790742324171, 5984.935197356818, 5993.760249590055, 5365.409804921534, 5480.314960629921, 5767.741226207179, 5777.50891174238, 5792.247007888947, 5702.543796496353, 5726.284214473191, 5731.684757338387, 5735.225744524436, 5747.770089196552, 5917.319508256605, 5946.702664866652, 5953.0, 5956.463918247378, 5984.251968503937, 3838.9232215356096, 4074.124063326399, 4077.816498257645, 4083.8162282697945, 4092.242935057024, 5585.664860108406, 5644.971775141098, 5670.177824215548, 5681.573881958952, 5683.2895888014, 5564.33228012641, 5574.693391863487, 5594.38461769197, 5621.606487545832, 5655.4910058094965, 5416.8104116355735, 5451.781928722965, 5526.640768350045, 5533.861653458695, 5562.388137304974, 5400.729963501888, 5406.540444062141, 5416.972915135557, 5420.810271640474, 5452.482014208644, 5399.190121481809, 5492.148716948879, 5552.33371995347, 5562.749676264497, 5576.525995290283, 5537.224788529689, 5578.748956296895, 5589.77640894376, 5594.524465420485, 5602.85992850182], "value": 4207.473819268475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.8599966006459, 758.3894964553535, 771.2800454985382, 813.9186081391858, 846.9026062002868, 15354.078755274677, 15723.0, 16371.59071023225, 16512.0, 16516.669666606675, 16414.589635259126, 16442.917785411057, 16527.504174874743, 16551.586210344718, 16575.66848663026, 646.0503060501056, 742.5210739073308, 755.395683453238, 763.1605234242345, 763.4121726270769, 753.2655660730799, 780.9336206422432, 784.52928243054, 785.1638005524127, 785.5600863516421, 786.2923368967918, 794.4041968523608, 799.2966189753009, 801.9478733882279, 804.2841870735022, 669.8760729265101, 781.620913856779, 796.7370767646663, 796.8207153390499, 797.2187256488645, 587.5270407322104, 588.6144575303183, 588.7350692188533, 589.0927970924771, 589.4223660812385, 15049.774253386207, 15164.696706065928, 15175.696486070327, 15195.62010949724, 15227.086374817547, 14241.145531268157, 14432.353628986159, 14515.491957781533, 14533.418663253562, 14600.488982885654, 12428.316442595653, 12444.377781111, 12544.372781360895, 12732.681682957907, 13120.803187952188, 4454.510003899569, 4504.346869703907, 4516.819327226907, 4602.125596136757, 4619.3763841881255, 82271.588642057, 82891.58554207251, 83341.58329208376, 83400.0, 83832.0, 10765.0, 10871.728206794734, 10932.453377331261, 11391.373474458818, 11747.588834390755, 20562.69155962662, 20940.58118837615, 20951.16195352169, 21016.264430745156, 21198.78801211999, 8481.703140390056, 8645.481271123752, 8671.739847804576, 8695.826083478294, 8886.822263554823, 4708.599769019672, 4832.903341933142, 4869.829555965524, 4876.366072410564, 5001.174806157016, 3379.2396710740377, 3463.2380876207194, 3542.628024057437, 3546.929061418757, 3572.4462708280257, 656.2158220926043, 657.5561495990188, 658.1148355461896, 661.1009027722293, 669.8492839111161, 716.9749058782918, 748.4947660329365, 761.482192109371, 776.8446310737875, 795.7572940253278, 10042.347247428894, 10246.0, 10354.585816567404, 10356.741081472872, 10499.160067194605, 624.1476452611522, 724.8912663100577, 756.1870988687074, 756.9129550101809, 762.4967521435826, 4569.9314510282375, 4807.975960120247, 4878.975605121952, 4899.363082799213, 4962.60299176065, 122757.38621306837, 124653.0, 125437.0, 127186.36406818092, 129107.0, 105247.0, 106099.0, 106393.46803265934, 106626.46686766672, 110108.4494577522, 56331.0, 58232.708836455546, 71471.6426417875, 83440.0, 85366.57316713501, 9369.578368973276, 9618.855717164388, 9892.307538472238, 10043.683382841225, 10347.482625868826, 36425.0, 41075.383869242585, 45571.316430254235, 46801.29798052964, 47069.293960591305, 72405.6379718098, 72692.63653681698, 73003.63498182475, 74655.0, 75150.0, 11106.555737770399, 11488.770224595628, 11538.192326537062, 11802.645920622395, 11807.173497855068, 14261.714765704835, 14402.135871847722, 14832.332545035715, 14903.403863845324, 15126.773098403753, 8514.957425212835, 11807.881921180679, 12503.475118153974, 12975.0, 13084.542041028519, 12392.566260181211, 12875.291858947849, 12900.0, 12976.935115324362, 13111.40998655082, 13853.584392468241, 14029.929850350683, 14285.785713214102, 14566.635834104232, 15144.318505667052, 11202.607908723156, 11558.595449159238, 11886.881131188577, 12045.096617753528, 12075.818862717242, 10721.147180573998, 11173.329600224013, 11211.943940280247, 11263.605773797877, 11297.43512824339], "value": 7553.619825421765, "unit": "score"}], "name": "dromaeo_css", "value": 7198.306370271914, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 110.0, 109.0, 110.0, 110.0, 110.0, 112.0, 111.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 127.0, 133.0, 195.0, 127.0, 125.0, 149.0, 123.0, 153.0, 125.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 162.0, 161.0, 161.0, 159.0, 161.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 126.0, 75.0, 78.0, 74.0, 74.0, 103.0, 74.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 114.0, 84.0, 85.0, 86.0, 114.0, 84.0, 85.0, 128.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 148.0, 148.0, 146.0, 147.0, 147.0, 148.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 57.0, 54.0, 62.0, 54.0, 55.0, 53.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 78.0, 79.0, 80.0, 81.0, 86.0, 107.0, 85.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 125.0, 124.0, 122.0, 129.0, 121.0, 118.0, 134.0, 154.0, 131.0], "value": 128.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 159.0, 158.0, 161.0, 164.0, 161.0, 159.0, 158.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 62.0, 63.0, 62.0, 66.0, 63.0, 62.0, 64.0, 62.0, 62.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1501.4, "alertThreshold": 2.0}]} 16:48:59 INFO - Return code: 0 16:48:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:48:59 INFO - # TBPL SUCCESS # 16:48:59 INFO - Running post-action listener: _resource_record_post_action 16:48:59 INFO - ##### 16:48:59 INFO - ##### Finished run-tests step (success) 16:48:59 INFO - ##### 16:48:59 INFO - Running post-run listener: _resource_record_post_run 16:48:59 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 550699008; Read time: 216; Write time: 593252 16:48:59 INFO - TinderboxPrint: CPU usage
13.2% 16:48:59 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 216 16:48:59 INFO - TinderboxPrint: I/O write bytes / time
550,699,008 / 593,252 16:48:59 INFO - TinderboxPrint: CPU idle
8,268.4 (86.8%) 16:48:59 INFO - TinderboxPrint: CPU user
1,211.4 (12.7%) 16:48:59 INFO - TinderboxPrint: Swap in / out
0 / 0 16:48:59 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 218484736; Read time: 0; Write time: 337508 16:48:59 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 12288; Write bytes: 287956992; Read time: 216; Write time: 242524 16:48:59 INFO - Running post-run listener: _upload_blobber_files 16:48:59 INFO - Blob upload gear active. 16:48:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:59 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 16:48:59 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:48:59 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:49:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:49:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:03 INFO - (blobuploader) - INFO - Done attempting. 16:49:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:49:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:04 INFO - (blobuploader) - INFO - Done attempting. 16:49:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:49:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:49:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:05 INFO - (blobuploader) - INFO - Done attempting. 16:49:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:05 INFO - Return code: 0 16:49:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f8792dc1e1b7972ea47ffa2fb1dabb193a62f588bad8a3c33fd4b03e3f58d23de2a2543fb609341f6cf24fc8e68c76042b372a4c561fe2668032162cfe5c0e00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e120b585bfd7e48da53d3097e6687cf5670d45de37d126407b4eb6209b39c8945519cb57bb7ea357ca17a6ef3696cd53fc9a0ddf7f2f6b6c9b2633b950d47339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b365cd7e71e428975b6d8c01fa25850ae5c043295ca5bec48d0384bdb5431af85fb555f0daee48cdd55992a3071db383affe4d59b7bd4c914bc51431e8cdd0e"} 16:49:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:05 INFO - Contents: 16:49:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f8792dc1e1b7972ea47ffa2fb1dabb193a62f588bad8a3c33fd4b03e3f58d23de2a2543fb609341f6cf24fc8e68c76042b372a4c561fe2668032162cfe5c0e00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e120b585bfd7e48da53d3097e6687cf5670d45de37d126407b4eb6209b39c8945519cb57bb7ea357ca17a6ef3696cd53fc9a0ddf7f2f6b6c9b2633b950d47339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b365cd7e71e428975b6d8c01fa25850ae5c043295ca5bec48d0384bdb5431af85fb555f0daee48cdd55992a3071db383affe4d59b7bd4c914bc51431e8cdd0e"} 16:49:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:05 INFO - Copying logs to upload dir... 16:49:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.099793 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-06-14 16:49:04.884865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 16:49:04.888837) ========= 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=db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767 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-Inbound/sha512/f8792dc1e1b7972ea47ffa2fb1dabb193a62f588bad8a3c33fd4b03e3f58d23de2a2543fb609341f6cf24fc8e68c76042b372a4c561fe2668032162cfe5c0e00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e120b585bfd7e48da53d3097e6687cf5670d45de37d126407b4eb6209b39c8945519cb57bb7ea357ca17a6ef3696cd53fc9a0ddf7f2f6b6c9b2633b950d47339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b365cd7e71e428975b6d8c01fa25850ae5c043295ca5bec48d0384bdb5431af85fb555f0daee48cdd55992a3071db383affe4d59b7bd4c914bc51431e8cdd0e"} build_url:https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011435 build_url: 'https://queue.taskcluster.net/v1/task/QuMqrqqbQWqSrrk3rd1fRg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f8792dc1e1b7972ea47ffa2fb1dabb193a62f588bad8a3c33fd4b03e3f58d23de2a2543fb609341f6cf24fc8e68c76042b372a4c561fe2668032162cfe5c0e00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e120b585bfd7e48da53d3097e6687cf5670d45de37d126407b4eb6209b39c8945519cb57bb7ea357ca17a6ef3696cd53fc9a0ddf7f2f6b6c9b2633b950d47339", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0b365cd7e71e428975b6d8c01fa25850ae5c043295ca5bec48d0384bdb5431af85fb555f0daee48cdd55992a3071db383affe4d59b7bd4c914bc51431e8cdd0e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 16:49:04.914842) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:49:04.915223) ========= 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=db0483c52d987ecb441a263b000001ad-1465925624.308677-837195767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004376 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 16:49:04.998762) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 16:49:04.999185) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 16:49:04.999545) ========= ========= Total master_lag: 0.24 =========