builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-050 starttime: 1465092257.87 results: success (0) buildid: 20160604163011 builduid: d31aea4deab1497091beff7e6580a18e revision: 5bc75c0144b55d799e80ea23a2e3244870d2f834 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:17.866212) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:17.866600) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:17.867116) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:17.951075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:17.951383) ========= 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=6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-04 19:04:18-- 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% 218M=0s 2016-06-04 19:04:18 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116776 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.085098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.085384) ========= 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=6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017845 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.134048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.134409) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5bc75c0144b55d799e80ea23a2e3244870d2f834 --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 5bc75c0144b55d799e80ea23a2e3244870d2f834 --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=6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-04 19:04:18,433 truncating revision to first 12 chars 2016-06-04 19:04:18,433 Setting DEBUG logging. 2016-06-04 19:04:18,433 attempt 1/10 2016-06-04 19:04:18,433 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/5bc75c0144b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 19:04:18,807 unpacking tar archive at: mozilla-inbound-5bc75c0144b5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.732189 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.879622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.879907) ========= script_repo_revision: 5bc75c0144b55d799e80ea23a2e3244870d2f834 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.880316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.880602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 19:04:18.892150) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-04 19:04:18.892425) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:04:19 INFO - MultiFileLogger online at 20160604 19:04:19 in /builds/slave/test 19:04:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 19:04:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:04:19 INFO - {'append_to_log': False, 19:04:19 INFO - 'base_work_dir': '/builds/slave/test', 19:04:19 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:04:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:04:19 INFO - 'branch': 'Mozilla-Inbound', 19:04:19 INFO - 'buildbot_json_path': 'buildprops.json', 19:04:19 INFO - 'config_files': ('talos/linux_config.py',), 19:04:19 INFO - 'default_actions': ('clobber', 19:04:19 INFO - 'read-buildbot-config', 19:04:19 INFO - 'download-and-extract', 19:04:19 INFO - 'populate-webroot', 19:04:19 INFO - 'create-virtualenv', 19:04:19 INFO - 'install', 19:04:19 INFO - 'run-tests'), 19:04:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:04:19 INFO - 'download_minidump_stackwalk': True, 19:04:19 INFO - 'download_symbols': 'ondemand', 19:04:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:04:19 INFO - 'tooltool.py': '/tools/tooltool.py', 19:04:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:04:19 INFO - '/tools/misc-python/virtualenv.py')}, 19:04:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:04:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:04:19 INFO - 'installer_path': 'installer.exe', 19:04:19 INFO - 'log_level': 'info', 19:04:19 INFO - 'log_name': 'talos', 19:04:19 INFO - 'log_to_console': True, 19:04:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:04:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:04:19 INFO - 'opt_config_files': (), 19:04:19 INFO - 'pip_index': False, 19:04:19 INFO - 'sps_profile': False, 19:04:19 INFO - 'sps_profile_interval': 0, 19:04:19 INFO - 'suite': 'dromaeojs-e10s', 19:04:19 INFO - 'system_bits': '32', 19:04:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:04:19 INFO - 'title': 'talos-linux64-ix-050', 19:04:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:04:19 INFO - 'use_talos_json': True, 19:04:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:04:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:04:19 INFO - 'work_dir': 'build'} 19:04:19 INFO - ##### 19:04:19 INFO - ##### Running clobber step. 19:04:19 INFO - ##### 19:04:19 INFO - Running pre-action listener: _resource_record_pre_action 19:04:19 INFO - Running main action method: clobber 19:04:19 INFO - rmtree: /builds/slave/test/build 19:04:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:04:20 INFO - Running post-action listener: _resource_record_post_action 19:04:20 INFO - ##### 19:04:20 INFO - ##### Finished clobber step (success) 19:04:20 INFO - ##### 19:04:20 INFO - ##### 19:04:20 INFO - ##### Running read-buildbot-config step. 19:04:20 INFO - ##### 19:04:20 INFO - Running pre-action listener: _resource_record_pre_action 19:04:20 INFO - Running main action method: read_buildbot_config 19:04:20 INFO - Using buildbot properties: 19:04:20 INFO - { 19:04:20 INFO - "project": "", 19:04:20 INFO - "product": "firefox", 19:04:20 INFO - "script_repo_revision": "production", 19:04:20 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 19:04:20 INFO - "repository": "", 19:04:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 19:04:20 INFO - "buildid": "20160604163011", 19:04:20 INFO - "pgo_build": "True", 19:04:20 INFO - "basedir": "/builds/slave/test", 19:04:20 INFO - "buildnumber": 660, 19:04:20 INFO - "slavename": "talos-linux64-ix-050", 19:04:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:04:20 INFO - "platform": "ubuntu64_hw", 19:04:20 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:04:20 INFO - "branch": "mozilla-inbound", 19:04:20 INFO - "repo_path": "integration/mozilla-inbound", 19:04:20 INFO - "revision": "5bc75c0144b55d799e80ea23a2e3244870d2f834", 19:04:20 INFO - "stage_platform": "linux64-pgo", 19:04:20 INFO - "builduid": "d31aea4deab1497091beff7e6580a18e", 19:04:20 INFO - "slavebuilddir": "test" 19:04:20 INFO - } 19:04:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:04:20 INFO - Running post-action listener: _resource_record_post_action 19:04:20 INFO - ##### 19:04:20 INFO - ##### Finished read-buildbot-config step (success) 19:04:20 INFO - ##### 19:04:20 INFO - ##### 19:04:20 INFO - ##### Running download-and-extract step. 19:04:20 INFO - ##### 19:04:20 INFO - Running pre-action listener: _resource_record_pre_action 19:04:20 INFO - Running main action method: download_and_extract 19:04:20 INFO - mkdir: /builds/slave/test/build/tests 19:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:04:20 INFO - https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:04:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:04:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:04:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:04:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:04:26 INFO - Downloaded 1361 bytes. 19:04:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:04:26 INFO - Using the following test package requirements: 19:04:26 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:04:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:04:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:04:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'jsshell-linux-x86_64.zip'], 19:04:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:04:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:04:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:04:26 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:04:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:04:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:04:26 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:04:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:04:26 INFO - https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:04:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:04:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:04:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:04:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:04:28 INFO - Downloaded 24441874 bytes. 19:04:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:04:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:04:29 INFO - Return code: 0 19:04:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:04:29 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')]} 19:04:29 INFO - https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:04:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:04:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:04:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:04:34 INFO - Downloaded 24441874 bytes. 19:04:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:04:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:04:36 INFO - Return code: 0 19:04:36 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')]} 19:04:36 INFO - https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:04:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:04:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:04:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:04:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:04:37 INFO - Downloaded 10833369 bytes. 19:04:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:04:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:04:37 INFO - Return code: 0 19:04: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')]} 19:04:37 INFO - https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:04:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:04:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:04:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:04:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:04:39 INFO - Downloaded 59176754 bytes. 19:04:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:04:39 INFO - mkdir: /builds/slave/test/properties 19:04:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:04:39 INFO - Writing to file /builds/slave/test/properties/build_url 19:04:39 INFO - Contents: 19:04:39 INFO - build_url:https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:04:40 INFO - Running post-action listener: _resource_record_post_action 19:04:40 INFO - Running post-action listener: set_extra_try_arguments 19:04:40 INFO - ##### 19:04:40 INFO - ##### Finished download-and-extract step (success) 19:04:40 INFO - ##### 19:04:40 INFO - ##### 19:04:40 INFO - ##### Running populate-webroot step. 19:04:40 INFO - ##### 19:04:40 INFO - Running pre-action listener: _resource_record_pre_action 19:04:40 INFO - Running main action method: populate_webroot 19:04:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:04:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:04:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:04:40 INFO - '--tppagecycles', 19:04:40 INFO - '7'], 19:04:40 INFO - 'tests': ['tsvgm']}}, 19:04:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:04:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:04:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:04:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:04:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:04:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:04:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['damp', 'tps']}, 19:04:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'tests': ['damp', 'tps']}, 19:04:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['dromaeo_dom']}, 19:04:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:04:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['a11yr', 19:04:40 INFO - 'ts_paint', 19:04:40 INFO - 'tpaint', 19:04:40 INFO - 'sessionrestore', 19:04:40 INFO - 'sessionrestore_no_auto_restore', 19:04:40 INFO - 'tabpaint']}, 19:04:40 INFO - 'other-e10s': {'tests': ['a11yr', 19:04:40 INFO - 'ts_paint', 19:04:40 INFO - 'tpaint', 19:04:40 INFO - 'sessionrestore', 19:04:40 INFO - 'sessionrestore_no_auto_restore', 19:04:40 INFO - 'tabpaint']}, 19:04:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:04:40 INFO - 'ts_paint', 19:04:40 INFO - 'tpaint', 19:04:40 INFO - 'sessionrestore', 19:04:40 INFO - 'sessionrestore_no_auto_restore', 19:04:40 INFO - 'tabpaint']}, 19:04:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:04:40 INFO - 'ts_paint', 19:04:40 INFO - 'tpaint', 19:04:40 INFO - 'sessionrestore', 19:04:40 INFO - 'sessionrestore_no_auto_restore', 19:04:40 INFO - 'tabpaint']}, 19:04:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['a11yr', 19:04:40 INFO - 'ts_paint', 19:04:40 INFO - 'tpaint', 19:04:40 INFO - 'sessionrestore', 19:04:40 INFO - 'sessionrestore_no_auto_restore', 19:04:40 INFO - 'tabpaint']}, 19:04:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['a11yr', 19:04:40 INFO - 'ts_paint', 19:04:40 INFO - 'tpaint', 19:04:40 INFO - 'sessionrestore', 19:04:40 INFO - 'sessionrestore_no_auto_restore', 19:04:40 INFO - 'tabpaint']}, 19:04:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['tsvgx', 19:04:40 INFO - 'tsvgr_opacity', 19:04:40 INFO - 'tart', 19:04:40 INFO - 'tscrollx', 19:04:40 INFO - 'cart']}, 19:04:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:04:40 INFO - 'tsvgr_opacity', 19:04:40 INFO - 'tart', 19:04:40 INFO - 'tscrollx', 19:04:40 INFO - 'cart']}, 19:04:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'talos_options': ['--disable-e10s'], 19:04:40 INFO - 'tests': ['tp5o']}, 19:04:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'tests': ['tp5o']}, 19:04:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'talos_options': ['--disable-e10s', 19:04:40 INFO - '--xperf_path', 19:04:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:04:40 INFO - 'tests': ['tp5n']}, 19:04:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:04:40 INFO - 'talos_options': ['--xperf_path', 19:04:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:04:40 INFO - 'tests': ['tp5n']}}, 19:04:40 INFO - 'talos.zip': {'path': '', 19:04:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:04:40 INFO - Running post-action listener: _resource_record_post_action 19:04:40 INFO - ##### 19:04:40 INFO - ##### Finished populate-webroot step (success) 19:04:40 INFO - ##### 19:04:40 INFO - ##### 19:04:40 INFO - ##### Running create-virtualenv step. 19:04:40 INFO - ##### 19:04:40 INFO - Running pre-action listener: _resource_record_pre_action 19:04:40 INFO - Running main action method: create_virtualenv 19:04:40 INFO - Creating virtualenv /builds/slave/test/build/venv 19:04:40 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 19:04:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:04:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:04:40 INFO - Using real prefix '/usr' 19:04:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:04:40 INFO - Installing distribute.............................................................................................................................................................................................done. 19:04:42 INFO - Installing pip.................done. 19:04:42 INFO - Return code: 0 19:04:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04: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 19:04: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 19:04:42 INFO - Using env: {'DISPLAY': ':0', 19:04:42 INFO - 'HOME': '/home/cltbld', 19:04:42 INFO - 'LANG': 'en_US.UTF-8', 19:04:42 INFO - 'LANGUAGE': 'en_US:en', 19:04:42 INFO - 'LOGNAME': 'cltbld', 19:04:42 INFO - 'MAIL': '/var/mail/cltbld', 19:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:42 INFO - 'MOZ_NO_REMOTE': '1', 19:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:42 INFO - 'NO_EM_RESTART': '1', 19:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:42 INFO - 'PWD': '/builds/slave/test', 19:04:42 INFO - 'SHELL': '/bin/bash', 19:04:42 INFO - 'SHLVL': '1', 19:04:42 INFO - 'TERM': 'linux', 19:04:42 INFO - 'TMOUT': '86400', 19:04:42 INFO - 'USER': 'cltbld', 19:04:42 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:42 INFO - '_': '/tools/buildbot/bin/python'} 19:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04: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 19:04:42 INFO - Cleaning up... 19:04:42 INFO - Return code: 0 19:04:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04: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 19:04: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 19:04:42 INFO - Using env: {'DISPLAY': ':0', 19:04:42 INFO - 'HOME': '/home/cltbld', 19:04:42 INFO - 'LANG': 'en_US.UTF-8', 19:04:42 INFO - 'LANGUAGE': 'en_US:en', 19:04:42 INFO - 'LOGNAME': 'cltbld', 19:04:43 INFO - 'MAIL': '/var/mail/cltbld', 19:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:43 INFO - 'MOZ_NO_REMOTE': '1', 19:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:43 INFO - 'NO_EM_RESTART': '1', 19:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:43 INFO - 'PWD': '/builds/slave/test', 19:04:43 INFO - 'SHELL': '/bin/bash', 19:04:43 INFO - 'SHLVL': '1', 19:04:43 INFO - 'TERM': 'linux', 19:04:43 INFO - 'TMOUT': '86400', 19:04:43 INFO - 'USER': 'cltbld', 19:04:43 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:43 INFO - '_': '/tools/buildbot/bin/python'} 19:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:43 INFO - Downloading/unpacking psutil>=3.1.1 19:04:43 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 19:04:43 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 19:04:43 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 19:04:43 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 19:04:43 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 19:04:43 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 19:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:04:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:04:45 INFO - Installing collected packages: psutil 19:04:45 INFO - Running setup.py install for psutil 19:04:45 INFO - building 'psutil._psutil_linux' extension 19:04:45 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 19:04: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 19:04:45 INFO - building 'psutil._psutil_posix' extension 19:04: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 19:04: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 19:04:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:04:45 INFO - Successfully installed psutil 19:04:45 INFO - Cleaning up... 19:04:45 INFO - Return code: 0 19:04:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04: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 19:04: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 19:04:45 INFO - Using env: {'DISPLAY': ':0', 19:04:45 INFO - 'HOME': '/home/cltbld', 19:04:45 INFO - 'LANG': 'en_US.UTF-8', 19:04:45 INFO - 'LANGUAGE': 'en_US:en', 19:04:45 INFO - 'LOGNAME': 'cltbld', 19:04:45 INFO - 'MAIL': '/var/mail/cltbld', 19:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:45 INFO - 'MOZ_NO_REMOTE': '1', 19:04:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:45 INFO - 'NO_EM_RESTART': '1', 19:04:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:45 INFO - 'PWD': '/builds/slave/test', 19:04:45 INFO - 'SHELL': '/bin/bash', 19:04:45 INFO - 'SHLVL': '1', 19:04:45 INFO - 'TERM': 'linux', 19:04:45 INFO - 'TMOUT': '86400', 19:04:45 INFO - 'USER': 'cltbld', 19:04:45 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:45 INFO - '_': '/tools/buildbot/bin/python'} 19:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:04:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:04:47 INFO - Installing collected packages: mozsystemmonitor 19:04:47 INFO - Running setup.py install for mozsystemmonitor 19:04:48 INFO - Successfully installed mozsystemmonitor 19:04:48 INFO - Cleaning up... 19:04:48 INFO - Return code: 0 19:04:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:04:48 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')]} 19:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:48 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')]} 19:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:48 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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04:48 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 19:04:48 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 19:04:48 INFO - Using env: {'DISPLAY': ':0', 19:04:48 INFO - 'HOME': '/home/cltbld', 19:04:48 INFO - 'LANG': 'en_US.UTF-8', 19:04:48 INFO - 'LANGUAGE': 'en_US:en', 19:04:48 INFO - 'LOGNAME': 'cltbld', 19:04:48 INFO - 'MAIL': '/var/mail/cltbld', 19:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:48 INFO - 'MOZ_NO_REMOTE': '1', 19:04:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:48 INFO - 'NO_EM_RESTART': '1', 19:04:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:48 INFO - 'PWD': '/builds/slave/test', 19:04:48 INFO - 'SHELL': '/bin/bash', 19:04:48 INFO - 'SHLVL': '1', 19:04:48 INFO - 'TERM': 'linux', 19:04:48 INFO - 'TMOUT': '86400', 19:04:48 INFO - 'USER': 'cltbld', 19:04:48 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:48 INFO - '_': '/tools/buildbot/bin/python'} 19:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:48 INFO - Downloading/unpacking jsonschema==2.5.1 19:04:48 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 19:04:48 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 19:04:48 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 19:04:48 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 19:04:48 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 19:04:48 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 19:04:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:04:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:50 INFO - Downloading functools32-3.2.3-2.tar.gz 19:04:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:04:50 INFO - warning: no files found matching '*.txt' 19:04:50 INFO - no previously-included directories found matching 'build' 19:04:50 INFO - no previously-included directories found matching 'dist' 19:04:50 INFO - no previously-included directories found matching '.git*' 19:04:50 INFO - Installing collected packages: jsonschema, functools32 19:04:50 INFO - Running setup.py install for functools32 19:04:50 INFO - warning: no files found matching '*.txt' 19:04:50 INFO - no previously-included directories found matching 'build' 19:04:50 INFO - no previously-included directories found matching 'dist' 19:04:50 INFO - no previously-included directories found matching '.git*' 19:04:50 INFO - Successfully installed jsonschema functools32 19:04:50 INFO - Cleaning up... 19:04:50 INFO - Return code: 0 19:04:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04: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 19:04: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 19:04:50 INFO - Using env: {'DISPLAY': ':0', 19:04:50 INFO - 'HOME': '/home/cltbld', 19:04:50 INFO - 'LANG': 'en_US.UTF-8', 19:04:50 INFO - 'LANGUAGE': 'en_US:en', 19:04:50 INFO - 'LOGNAME': 'cltbld', 19:04:50 INFO - 'MAIL': '/var/mail/cltbld', 19:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:50 INFO - 'MOZ_NO_REMOTE': '1', 19:04:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:50 INFO - 'NO_EM_RESTART': '1', 19:04:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:50 INFO - 'PWD': '/builds/slave/test', 19:04:50 INFO - 'SHELL': '/bin/bash', 19:04:50 INFO - 'SHLVL': '1', 19:04:50 INFO - 'TERM': 'linux', 19:04:50 INFO - 'TMOUT': '86400', 19:04:50 INFO - 'USER': 'cltbld', 19:04:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:50 INFO - '_': '/tools/buildbot/bin/python'} 19:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:50 INFO - Downloading/unpacking blobuploader==1.2.4 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:52 INFO - Downloading blobuploader-1.2.4.tar.gz 19:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:04:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:04:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:04:53 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 19:04:53 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 19:04:53 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 19:04:53 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 19:04:53 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 19:04:53 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 19:04:53 INFO - Downloading docopt-0.6.1.tar.gz 19:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:04:53 INFO - Installing collected packages: blobuploader, requests, docopt 19:04:53 INFO - Running setup.py install for blobuploader 19:04:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:04:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:04:53 INFO - Running setup.py install for requests 19:04:53 INFO - Running setup.py install for docopt 19:04:54 INFO - Successfully installed blobuploader requests docopt 19:04:54 INFO - Cleaning up... 19:04:54 INFO - Return code: 0 19:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04:54 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')]} 19:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:54 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')]} 19:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04:54 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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04:54 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 19:04:54 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 19:04:54 INFO - Using env: {'DISPLAY': ':0', 19:04:54 INFO - 'HOME': '/home/cltbld', 19:04:54 INFO - 'LANG': 'en_US.UTF-8', 19:04:54 INFO - 'LANGUAGE': 'en_US:en', 19:04:54 INFO - 'LOGNAME': 'cltbld', 19:04:54 INFO - 'MAIL': '/var/mail/cltbld', 19:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:54 INFO - 'MOZ_NO_REMOTE': '1', 19:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:54 INFO - 'NO_EM_RESTART': '1', 19:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:54 INFO - 'PWD': '/builds/slave/test', 19:04:54 INFO - 'SHELL': '/bin/bash', 19:04:54 INFO - 'SHLVL': '1', 19:04:54 INFO - 'TERM': 'linux', 19:04:54 INFO - 'TMOUT': '86400', 19:04:54 INFO - 'USER': 'cltbld', 19:04:54 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:54 INFO - '_': '/tools/buildbot/bin/python'} 19:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:54 INFO - Running setup.py (path:/tmp/pip-UhUAqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:54 INFO - Running setup.py (path:/tmp/pip-_VkcVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:54 INFO - Running setup.py (path:/tmp/pip-T_DlXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:54 INFO - Running setup.py (path:/tmp/pip-ZNLzI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:54 INFO - Running setup.py (path:/tmp/pip-8r7DTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:54 INFO - Running setup.py (path:/tmp/pip-Fia6BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:54 INFO - Running setup.py (path:/tmp/pip-nTKXB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:54 INFO - Running setup.py (path:/tmp/pip-uU03HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:54 INFO - Running setup.py (path:/tmp/pip-dUSJAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:54 INFO - Running setup.py (path:/tmp/pip-sOKeOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:55 INFO - Running setup.py (path:/tmp/pip-LooqsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:55 INFO - Running setup.py (path:/tmp/pip-X5xD3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:55 INFO - Running setup.py (path:/tmp/pip-d1QpcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:55 INFO - Running setup.py (path:/tmp/pip-yn9I91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:55 INFO - Running setup.py (path:/tmp/pip-cTa4Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:55 INFO - Running setup.py (path:/tmp/pip-36iwqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:55 INFO - Running setup.py (path:/tmp/pip-tRv1Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:04:55 INFO - Running setup.py install for manifestparser 19:04:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:04:55 INFO - Running setup.py install for mozcrash 19:04:55 INFO - Running setup.py install for mozdebug 19:04:55 INFO - Running setup.py install for mozdevice 19:04:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:04:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:04:55 INFO - Running setup.py install for mozfile 19:04:56 INFO - Running setup.py install for mozhttpd 19:04:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Running setup.py install for mozinfo 19:04:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Running setup.py install for mozInstall 19:04:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Running setup.py install for mozleak 19:04:56 INFO - Running setup.py install for mozlog 19:04:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Running setup.py install for moznetwork 19:04:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Running setup.py install for mozprocess 19:04:56 INFO - Running setup.py install for mozprofile 19:04:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:04:56 INFO - Running setup.py install for mozrunner 19:04:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:04:57 INFO - Running setup.py install for mozscreenshot 19:04:57 INFO - Running setup.py install for moztest 19:04:57 INFO - Running setup.py install for mozversion 19:04:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:04:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:04:57 INFO - Cleaning up... 19:04:57 INFO - Return code: 0 19:04:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:04: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')]} 19:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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')]} 19:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:04: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:04: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 19:04: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 19:04:57 INFO - Using env: {'DISPLAY': ':0', 19:04:57 INFO - 'HOME': '/home/cltbld', 19:04:57 INFO - 'LANG': 'en_US.UTF-8', 19:04:57 INFO - 'LANGUAGE': 'en_US:en', 19:04:57 INFO - 'LOGNAME': 'cltbld', 19:04:57 INFO - 'MAIL': '/var/mail/cltbld', 19:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:57 INFO - 'MOZ_NO_REMOTE': '1', 19:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:57 INFO - 'NO_EM_RESTART': '1', 19:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:57 INFO - 'PWD': '/builds/slave/test', 19:04:57 INFO - 'SHELL': '/bin/bash', 19:04:57 INFO - 'SHLVL': '1', 19:04:57 INFO - 'TERM': 'linux', 19:04:57 INFO - 'TMOUT': '86400', 19:04:57 INFO - 'USER': 'cltbld', 19:04:57 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:57 INFO - '_': '/tools/buildbot/bin/python'} 19:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:04:57 INFO - Running setup.py (path:/tmp/pip-LK18N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:04: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)) 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:04:57 INFO - Running setup.py (path:/tmp/pip-e4a4i9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:04: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)) 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:04:57 INFO - Running setup.py (path:/tmp/pip-SjxnGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:04: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)) 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:04:57 INFO - Running setup.py (path:/tmp/pip-LCcZ7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:04: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)) 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:04:57 INFO - Running setup.py (path:/tmp/pip-iZNTtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:04: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)) 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:04:57 INFO - Running setup.py (path:/tmp/pip-TjJAqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:04: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)) 19:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:04:57 INFO - Running setup.py (path:/tmp/pip-elBNYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:04:58 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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:04:58 INFO - Running setup.py (path:/tmp/pip-VNMb8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:04:58 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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:04:58 INFO - Running setup.py (path:/tmp/pip-64gCqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:04:58 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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:04:58 INFO - Running setup.py (path:/tmp/pip-rQXPnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:04:58 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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:04:58 INFO - Running setup.py (path:/tmp/pip-sPARqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:04: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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:04:58 INFO - Running setup.py (path:/tmp/pip-ZB2fVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:04: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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:04:58 INFO - Running setup.py (path:/tmp/pip-qQZv5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:04: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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:04:58 INFO - Running setup.py (path:/tmp/pip-VMfA5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:04: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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:04:58 INFO - Running setup.py (path:/tmp/pip-szT9V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:04: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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:04:58 INFO - Running setup.py (path:/tmp/pip-oI4YOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:04: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)) 19:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:04:58 INFO - Running setup.py (path:/tmp/pip-TjNqcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:04: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)) 19:04: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)) 19:04: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)) 19:04: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)) 19:04: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)) 19:04: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)) 19:04:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:04: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 19:05:00 INFO - Downloading blessings-1.6.tar.gz 19:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:05:00 INFO - Installing collected packages: blessings 19:05:00 INFO - Running setup.py install for blessings 19:05:00 INFO - Successfully installed blessings 19:05:00 INFO - Cleaning up... 19:05:00 INFO - Return code: 0 19:05:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:05:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:05:01 INFO - Reading from file tmpfile_stdout 19:05:01 INFO - Current package versions: 19:05:01 INFO - argparse == 1.2.1 19:05:01 INFO - blessings == 1.6 19:05:01 INFO - blobuploader == 1.2.4 19:05:01 INFO - docopt == 0.6.1 19:05:01 INFO - functools32 == 3.2.3-2 19:05:01 INFO - jsonschema == 2.5.1 19:05:01 INFO - manifestparser == 1.1 19:05:01 INFO - mozInstall == 1.12 19:05:01 INFO - mozcrash == 0.17 19:05:01 INFO - mozdebug == 0.1 19:05:01 INFO - mozdevice == 0.48 19:05:01 INFO - mozfile == 1.2 19:05:01 INFO - mozhttpd == 0.7 19:05:01 INFO - mozinfo == 0.9 19:05:01 INFO - mozleak == 0.1 19:05:01 INFO - mozlog == 3.2 19:05:01 INFO - moznetwork == 0.27 19:05:01 INFO - mozprocess == 0.22 19:05:01 INFO - mozprofile == 0.28 19:05:01 INFO - mozrunner == 6.11 19:05:01 INFO - mozscreenshot == 0.1 19:05:01 INFO - mozsystemmonitor == 0.3 19:05:01 INFO - moztest == 0.7 19:05:01 INFO - mozversion == 1.4 19:05:01 INFO - psutil == 3.1.1 19:05:01 INFO - requests == 1.2.3 19:05:01 INFO - wsgiref == 0.1.2 19:05:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:05: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')]} 19:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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')]} 19:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05:01 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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:05:01 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 19:05:01 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 19:05:01 INFO - Using env: {'DISPLAY': ':0', 19:05:01 INFO - 'HOME': '/home/cltbld', 19:05:01 INFO - 'LANG': 'en_US.UTF-8', 19:05:01 INFO - 'LANGUAGE': 'en_US:en', 19:05:01 INFO - 'LOGNAME': 'cltbld', 19:05:01 INFO - 'MAIL': '/var/mail/cltbld', 19:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:01 INFO - 'MOZ_NO_REMOTE': '1', 19:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:01 INFO - 'NO_EM_RESTART': '1', 19:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:01 INFO - 'PWD': '/builds/slave/test', 19:05:01 INFO - 'SHELL': '/bin/bash', 19:05:01 INFO - 'SHLVL': '1', 19:05:01 INFO - 'TERM': 'linux', 19:05:01 INFO - 'TMOUT': '86400', 19:05:01 INFO - 'USER': 'cltbld', 19:05:01 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:01 INFO - '_': '/tools/buildbot/bin/python'} 19:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05: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)) 19:05:01 INFO - Cleaning up... 19:05:01 INFO - Return code: 0 19:05:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:05: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')]} 19:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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')]} 19:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:05: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 19:05: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 19:05:01 INFO - Using env: {'DISPLAY': ':0', 19:05:01 INFO - 'HOME': '/home/cltbld', 19:05:01 INFO - 'LANG': 'en_US.UTF-8', 19:05:01 INFO - 'LANGUAGE': 'en_US:en', 19:05:01 INFO - 'LOGNAME': 'cltbld', 19:05:01 INFO - 'MAIL': '/var/mail/cltbld', 19:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:01 INFO - 'MOZ_NO_REMOTE': '1', 19:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:01 INFO - 'NO_EM_RESTART': '1', 19:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:01 INFO - 'PWD': '/builds/slave/test', 19:05:01 INFO - 'SHELL': '/bin/bash', 19:05:01 INFO - 'SHLVL': '1', 19:05:01 INFO - 'TERM': 'linux', 19:05:01 INFO - 'TMOUT': '86400', 19:05:01 INFO - 'USER': 'cltbld', 19:05:01 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:01 INFO - '_': '/tools/buildbot/bin/python'} 19:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:05:01 INFO - Cleaning up... 19:05:01 INFO - Return code: 0 19:05:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:05: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')]} 19:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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')]} 19:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:05: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 0x7f913792ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ed350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, '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': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', '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 19:05: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 19:05: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 19:05:01 INFO - Using env: {'DISPLAY': ':0', 19:05:01 INFO - 'HOME': '/home/cltbld', 19:05:01 INFO - 'LANG': 'en_US.UTF-8', 19:05:01 INFO - 'LANGUAGE': 'en_US:en', 19:05:01 INFO - 'LOGNAME': 'cltbld', 19:05:01 INFO - 'MAIL': '/var/mail/cltbld', 19:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:01 INFO - 'MOZ_NO_REMOTE': '1', 19:05:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:01 INFO - 'NO_EM_RESTART': '1', 19:05:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:01 INFO - 'PWD': '/builds/slave/test', 19:05:01 INFO - 'SHELL': '/bin/bash', 19:05:01 INFO - 'SHLVL': '1', 19:05:01 INFO - 'TERM': 'linux', 19:05:01 INFO - 'TMOUT': '86400', 19:05:01 INFO - 'USER': 'cltbld', 19:05:01 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:01 INFO - '_': '/tools/buildbot/bin/python'} 19:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:05:01 INFO - Downloading/unpacking flake8 19:05: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 19:05: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 19:05: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 19:05: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 19:05: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 19:05: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 19:05:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:05:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:05: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 19:05: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 19:05: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 19:05: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 19:05: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 19:05: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 19:05:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:05:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:05: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 19:05: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 19:05: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 19:05: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 19:05: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 19:05: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 19:05:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:05:04 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 19:05:04 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 19:05:04 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 19:05:04 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 19:05:04 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 19:05:04 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 19:05:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:05:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:05:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:05:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:05:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:05:04 INFO - no previously-included directories found matching 'docs/_build' 19:05:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:05:04 INFO - Running setup.py install for pep8 19:05:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:05:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:05:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:05:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:05:04 INFO - no previously-included directories found matching 'docs/_build' 19:05:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:05:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:05:04 INFO - Cleaning up... 19:05:04 INFO - Return code: 0 19:05:04 INFO - Running post-action listener: _resource_record_post_action 19:05:04 INFO - Running post-action listener: _start_resource_monitoring 19:05:04 INFO - Starting resource monitoring. 19:05:04 INFO - ##### 19:05:04 INFO - ##### Finished create-virtualenv step (success) 19:05:04 INFO - ##### 19:05:04 INFO - ##### 19:05:04 INFO - ##### Running install step. 19:05:04 INFO - ##### 19:05:04 INFO - Running pre-action listener: _resource_record_pre_action 19:05:04 INFO - Running main action method: install 19:05:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:05:04 INFO - Reading from file tmpfile_stdout 19:05:04 INFO - Detecting whether we're running mozinstall >=1.0... 19:05:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:05:04 INFO - Reading from file tmpfile_stdout 19:05:04 INFO - Output received: 19:05:04 INFO - Usage: mozinstall [options] installer 19:05:04 INFO - Options: 19:05:04 INFO - -h, --help show this help message and exit 19:05:04 INFO - -d DEST, --destination=DEST 19:05:04 INFO - Directory to install application into. [default: 19:05:04 INFO - "/builds/slave/test"] 19:05:04 INFO - --app=APP Application being installed. [default: firefox] 19:05:04 INFO - mkdir: /builds/slave/test/build/application 19:05:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:05:21 INFO - Reading from file tmpfile_stdout 19:05:21 INFO - Output received: 19:05:21 INFO - /builds/slave/test/build/application/firefox/firefox 19:05:21 INFO - Running post-action listener: _resource_record_post_action 19:05:21 INFO - ##### 19:05:21 INFO - ##### Finished install step (success) 19:05:21 INFO - ##### 19:05:21 INFO - ##### 19:05:21 INFO - ##### Running run-tests step. 19:05:21 INFO - ##### 19:05:21 INFO - Running pre-action listener: _resource_record_pre_action 19:05:21 INFO - Running main action method: run_tests 19:05:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:05:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:05:21 INFO - Python 2.7.3 19:05:21 INFO - Return code: 0 19:05:21 INFO - grabbing minidump binary from tooltool 19:05: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')]} 19:05: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 0x267c1c0>, '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 0x267d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x267d550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:05: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 19:05: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 19:05: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 19:05:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:05:21 INFO - Return code: 0 19:05:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:05:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:05:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:05:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:05:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:05:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:05:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:05:22 INFO - Return code: 0 19:05: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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:05: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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:05:22 INFO - Using env: {'DISPLAY': ':0', 19:05:22 INFO - 'HOME': '/home/cltbld', 19:05:22 INFO - 'LANG': 'en_US.UTF-8', 19:05:22 INFO - 'LANGUAGE': 'en_US:en', 19:05:22 INFO - 'LOGNAME': 'cltbld', 19:05:22 INFO - 'MAIL': '/var/mail/cltbld', 19:05:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:05:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:05:22 INFO - 'MOZ_NO_REMOTE': '1', 19:05:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:05:22 INFO - 'NO_EM_RESTART': '1', 19:05:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:05:22 INFO - 'PWD': '/builds/slave/test', 19:05:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:05:22 INFO - 'SHELL': '/bin/bash', 19:05:22 INFO - 'SHLVL': '1', 19:05:22 INFO - 'TERM': 'linux', 19:05:22 INFO - 'TMOUT': '86400', 19:05:22 INFO - 'USER': 'cltbld', 19:05:22 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716', 19:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:05:22 INFO - '_': '/tools/buildbot/bin/python'} 19:05: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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:05:22 INFO - mozversion application_buildid: 20160604163011 19:05:22 INFO - mozversion application_changeset: 5bc75c0144b55d799e80ea23a2e3244870d2f834 19:05:22 INFO - mozversion application_display_name: Nightly 19:05:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:05:22 INFO - mozversion application_name: Firefox 19:05:22 INFO - mozversion application_remotingname: firefox 19:05:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:05:22 INFO - mozversion application_vendor: Mozilla 19:05:22 INFO - mozversion application_version: 49.0a1 19:05:22 INFO - mozversion platform_buildid: 20160604163011 19:05:22 INFO - mozversion platform_changeset: 5bc75c0144b55d799e80ea23a2e3244870d2f834 19:05:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:05:22 INFO - mozversion platform_version: 49.0a1 19:05:22 INFO - using testdate: 1465092322 19:05:22 INFO - actual date: 1465092322 19:05:22 INFO - starting webserver on 'localhost:41830' 19:05:22 INFO - SUITE-START | Running 2 tests 19:05:22 INFO - TEST-START | dromaeo_css 19:05:22 INFO - operating with platform_type : linux_ 19:05:22 INFO - Initialising browser for dromaeo_css test... 19:05:22 INFO - TEST-INFO | started process 10894 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyFBRlV/profile http://localhost:41830/getInfo.html) 19:05:27 INFO - PROCESS | 10894 | __metrics Screen width/height:1600/1200 19:05:27 INFO - PROCESS | 10894 | colorDepth:24 19:05:27 INFO - PROCESS | 10894 | Browser inner width/height: 1024/697 19:05:27 INFO - PROCESS | 10894 | __metrics 19:05:28 ERROR - PROCESS | 10894 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 19:05:29 INFO - TEST-INFO | 10894: exit 0 19:05:29 INFO - Browser initialized. 19:05:29 INFO - Running cycle 1/1 for dromaeo_css test... 19:05:29 INFO - TEST-INFO | started process 11014 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyFBRlV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:05:40 INFO - PROCESS | 11014 | RSS: Main: 167284736 19:05:40 INFO - PROCESS | 11014 | 19:06:30 INFO - PROCESS | 11014 | 1465092390571 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] 19:08:38 INFO - PROCESS | 11014 | Cycle 1(1): loaded http://localhost:41830/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41830/tests/dromaeo/cssquery-ext.html) 19:08:39 INFO - PROCESS | 11014 | RSS: Main: 170524672 19:08:39 INFO - PROCESS | 11014 | 19:11:41 INFO - PROCESS | 11014 | Cycle 1(1): loaded http://localhost:41830/tests/dromaeo/cssquery-ext.html (next: http://localhost:41830/tests/dromaeo/cssquery-jquery.html) 19:11:41 INFO - PROCESS | 11014 | RSS: Main: 169877504 19:11:41 INFO - PROCESS | 11014 | 19:14:43 INFO - PROCESS | 11014 | Cycle 1(1): loaded http://localhost:41830/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41830/tests/dromaeo/cssquery-mootools.html) 19:14:44 INFO - PROCESS | 11014 | RSS: Main: 171130880 19:14:44 INFO - PROCESS | 11014 | 19:17:45 INFO - PROCESS | 11014 | Cycle 1(1): loaded http://localhost:41830/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41830/tests/dromaeo/cssquery-prototype.html) 19:17:46 INFO - PROCESS | 11014 | RSS: Main: 168579072 19:17:46 INFO - PROCESS | 11014 | 19:20:48 INFO - PROCESS | 11014 | Cycle 1(1): loaded http://localhost:41830/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41830/tests/dromaeo/cssquery-yui.html) 19:20:49 INFO - PROCESS | 11014 | RSS: Main: 170192896 19:20:49 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | Cycle 1(1): loaded http://localhost:41830/tests/dromaeo/cssquery-yui.html (next: http://localhost:41830/tests/dromaeo/cssquery-dojo.html) 19:23:52 INFO - PROCESS | 11014 | RSS: Main: 171741184 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | __start_tp_report 19:23:52 INFO - PROCESS | 11014 | _x_x_mozilla_page_load 19:23:52 INFO - PROCESS | 11014 | _x_x_mozilla_page_load_details 19:23:52 INFO - PROCESS | 11014 | |i|pagename|runs| 19:23:52 INFO - PROCESS | 11014 | |0;dojo.html;3056.465118604244;3102.7207551320375;3113.595232619759;3127.0306205076413;3147.8740850365984;5322.866928326794;5337.6797392156495;5368.91946620801;5390.380106287778;5484.862878428041;5162.148245539482;5322.387925388582;5336.38631557371;5368.0605893968495;5373.596980226489;4848.369711937452;4864.367632207808;4945.876353091166;5035.672681275723;5085.110105731501;4872.293517439969;5057.342545469102;5077.162268225738;5082.0598189334905;5090.109230884599;4934.037862616809;4953.578945789613;4954.801807927679;4956.107900577895;4963.528464795839;4986.526280003394;4986.725730084844;4988.276699878534;5001.349824522788;5035.320231768737;4954.479779623124;4989.226669866177;4990.051890140898;4995.075910956485;5016.523430274118;5109.744512774347;5309;5332.146856502941;5336.119540275849;5395.811146609889;5831.358550559435;5834.941650583482;5846.473817356458;5850.239468869062;5855.502282306009;5698.373178950312;5885.676287804212;5886.381929897342;5917.14201440793;5921.585489015771;5331.680099194061;5348.117560602496;5395.5953303502;5400.972995134999;5407.297051383334;5865.296164460292;5903.3801450847905;5906.468417842329;5912.438318359749;5917.142014407887;5541.168824676251;5627.943720562824;5639.43605639441;5661.377248502661;5664.320281566238;5725.1698503716725;5832.737526811304;5833.125031245348;5841.88316233673;5885.793997210077;5612.943870561324;5687.061634830289;5785.768569257267;5787.88424231513;5808.3901190375245;5726.484616384545;5752.079667253303;5752.568557358215;5765.827025189251;5807.82576522705;4359.825606975749;4386.583274588909;4398.538153493901;4404.185225733249;4412.338149277634;3863.903402414906;3902.824372903226;3910.589388114264;3921.0785465415606;3934.724569280123;5651.350094739076;5656.886862262814;5670.347909990322;5683.119116536944;5722.4277572242445;5896.086106653475;5932.851678707981;5939.524838012949;5946.108083787466;5954.434328738763;5377.569794416438;5519.862003449946;5542.445755424506;5572.219889215513;5655.604107712503;4984.077945580079;5009.12340340439;5034.496550345009;5037.722925239073;5072.771725272371;6018.247719035121;6036.124761909498;6049.5462840287155;6051.364606716319;6062.727177277033;5652.0674088774895;5846.590738648424;5848.912266316093;5864.882702346014;5917.260342457193;6043.486303664238;6056.212692350143;6070.271567411763;6083.330833608211;6110.602810817282;5495.120780675073;5797.188393624811;5805.535557155502;5810.477057064884;5814.854628634318;5822.446867547576;5825.155352473952;5871.882562348815;5909.23179986599;5912.73392697321;5725.169850371756;5729.9713501432225;5740.540756739534;5741.081426971665;5741.91387129185;5571.498565129158;5603.991281569357;5705.7432415542235;5715.428457154251;5726.398728133486;5487.807926722546;5506.64206826555;5542.307211598551;5562.527185189304;5661.490465858093;5675.631083979528;5695.430456954271;5700.059490184073;5735.168400581976;5753.9136912945505;5332.840014799563;5556.305461817273;5560.277163968658;5575.303087114111;5634.323881134126 19:23:52 INFO - PROCESS | 11014 | |1;ext.html;590.3152343281791;622.4460230394948;630.1367127035961;651.3486513486514;664.2593508238313;41274.38088428672;42490;42595.57404425955;45704.771476142654;45705.77147114268;40908.38637420441;41006.38490422638;41428.17143657125;41439;41690.37464438036;8382.538960357193;8620;8641.049484556694;8641.783955401133;8657.134286571323;8542.658293668277;8562;8584.0557538671;8598.742037738877;8613.095624959384;8586.828263434727;8590.699325523608;8605.827883442358;8621.827563448727;8660.437071590326;8521.44610600309;8576.485410875368;8594.054653988056;8601.354898382615;8602.526861022641;8619.310455163572;8634.26608738258;8677.479351238882;8692.695755648585;8706.869396959051;39434.80282598597;39919;40018.199636007404;40140;40146;3447.465642825366;3636.7454278200535;3682.1346983458866;3698.5746639136305;4216.325387937946;29639.55540666892;30019.69980300191;30094.699053009408;30269.545956810445;30972.8451357744;4298.3552467130185;4316.352547117926;4327.7619730914785;4470.575295346936;4492.3710680504755;204785;205240;206773;206859;207129;35063.64936350655;55179.44820551743;55309;55503.44496555063;55664;34570.82714586411;35886.1028474285;35948.82025589855;36134.457983130276;36204;15514.146721930401;15618.141002244758;15751.527454176394;15841.049537027815;15897.28462219203;13102.868971310356;13395;13397.866021339856;13426.127301725357;13449.058565900294;8332.708355207538;8363.372747043995;8405.117462666332;8422;8454.619542120496;3256.544083828266;3257.2019855135645;3288.6711328866922;3309.635940046593;3370.208001119735;970.3838062830154;1039.4750650921237;1075.1130317488103;1092.2136062035322;1166.4867458318313;32133.19667008297;32566.511502327485;32767.18082047968;32864.342713145605;32937.83531082329;1130.9377984210782;1144.0390072339276;1155.156735583029;1209.7822391969355;1259.5465632372338;16976.915115424345;17093.401730939357;17141.48575542736;17310.134493275287;17449.912750436168;596331;599082;600970.9854352188;608284;611022;58942.70528647415;59853.40146598478;59966.700166498886;59968.10047849286;59999;1341.1081630715464;1402.4600528796486;1410.6261840612246;1434.1968497641146;1438.5971927860217;30529;31815.522767158047;31891.36217275595;31948.36103277875;31977.520337195427;49714.254286185016;50692;50855.23717144172;50919.236211457595;50935.235971461196;507811;514525;517294;517704;520215;27813.44373112567;28303.575446367908;28308.858455708414;28319.716802831706;28364;47844;47979.28031079467;49423.25865112098;49532.25701614551;49831.750841245565;40692;41713.58286417097;41766.37350439685;42005;42248.36627450529;41180;41265;41458.79270603628;41494.58505414907;41648.79175604103;41994;42198.367024495274;42971.57028429676;43221.78389108034;44773.32840007338;34644.307113858085;37362.626373737;37505.437418438196;37895.43156852705;38221;28868.27829304284;29048.12855614335;29102.563461548518;29162.56256156202;29310.706892931652 19:23:52 INFO - PROCESS | 11014 | |2;jquery.html;398.6352487473961;403.34859202388145;406.39041437843235;407.5924075924076;423.7097588152116;17526.82473175268;19248.80751192491;19313.71029434558;19444.611107777837;20260.493487662814;18920.905395473037;18958;19025.524361890926;19581.314653987112;19620.901895490537;5461.317335333083;5494.09347457669;5666.914996275039;5700.942990570104;5701.800436984707;5576.804811831586;5623.718814059301;5624.943750562504;5627.887442251153;5629.802956896509;5384.865378365553;5561.221428999943;5594.13290939905;5611.80358687446;5613.214150019001;5408.945910540883;5482.725863706819;5587;5589.189567512729;5591.804286849941;4995.825146119869;5001.299818025479;5027.673201241944;5037.49625037497;5050.3434553507805;22559.323220303253;22747.886260568757;22751.317460476213;23231.883840580856;23281.18515851954;2351.8000969917407;2434.075051480444;2630.6185603087447;2671.6526851509375;2672.719364466742;14410.639734006734;14496.637584060378;14633.853661463461;15089.54731358061;15167;1936.3416438410868;2020.605981833543;2043.427840204745;2055.650539408304;2055.7635871874777;81411;81483;82564;83742;83883.99999999939;9183.311251656152;9516.143547080796;9828.4102953823;9887.011298870199;10322.12261957742;12596.118271721074;12743.617691469271;12912.870871291354;13087.280199589113;13128.409221585052;5942.70286485674;6027.547933904974;6818.454523638097;7603.125640551297;7768.844623107506;7298.051253337032;7340.816479588053;7360.631968401559;7402.407807375397;7771.844563108706;3949.19041596472;4021.2560676274984;4027.3958906163725;4129.12124983132;4192.538820729717;1953.540917884296;1988.9005549722456;2000.2099170827441;2003.8096380843797;2017.6368253714472;862.7670528957146;953.3374304858057;1012.1649639047668;1023.892491288404;1042.4244840198194;17967.64064718698;18447.354342598213;18536.258549658134;18584.81415185858;18678.906605467157;951.7906060666643;980.7646164920368;1002.3785253143097;1030.9793804123876;1088.6516314779353;10147.746306342253;10377.066064054272;10423.5830566778;10448.477576121164;10459.68620941373;480087;488710;488816;491099;491124;59802.700986495656;60797;62569;95342;95565.52217239009;970.5632465390744;978.5400861595039;979.835132521958;983.1692220074026;997.2371136080903;16511.422100226435;16832.915835420743;16849;16895.32418703238;16930.492085237463;43378.349324759525;43801.12397751964;45851.77074114608;46049.30926036045;46165.30752038789;60580.39419605924;61820.69089654523;62566.558839264275;63369.68315158579;63458;7600.3539699126195;7856.25365590268;8077.111517733162;8393.118722534045;8467.534285614103;12926.224426534438;13387.196768193939;13676.042677012514;13694.041417101105;13742.725145497234;8695.86956195645;11840.171188016757;11964.2651815488;12094;12222.20555663879;10948.945255273673;11091.5563377464;11322;11468.655940321803;11782.116341274268;11939.820902686293;12375;12469.563565275172;12522.686932826753;12746.235225886476;7595.126560445619;7605.847883042419;7879.487833290817;8052.798680033046;8315.83368332642;11132.721681958015;11894.524219031488;12078.879211207775;12165.939170304446;12182.329971851634 19:23:52 INFO - PROCESS | 11014 | |3;mootools.html;1490.5528341497552;1538.0002998051266;1694.7627332173497;1798.3294807211298;1841.327915310912;7897.763067107996;8663.95668021659;8720.651173953034;9395.483248421326;9406.341556091067;8742.431741936769;8980.101989801016;9069.047749986255;9076.410033347827;9096.499692516909;2383.368407372048;2415.3237093613816;2420.213430635051;2422.878856057199;2425.6361545768186;2410.337157281744;2414.770596793302;2417.105670901769;2418.093215044358;2424.260600516842;2358.870262135582;2397.1010870923405;2401.435662619283;2410.939186757821;2426.5389575980594;2324.476992676651;2365.491419344832;2368.1829768729763;2385.1294277588727;2394.150076722756;2355.0815182079004;2361.362432143329;2369.1115831563166;2371.4071482129466;2401.9639705404256;8972.057933916974;9075;9091.499967501784;9092.363534552584;9148.085191480865;5198.220266959948;5325.707086110262;5330.413654498002;5334.67991920486;5336.17289320156;3360.260742636616;3392.3215356928713;3429.451287793966;3469.8265086745814;3482.04243832948;1141.4806263150247;1157.6700640317554;1168.3924359333184;1177.941102944858;2191.605511008018;12373.69065773354;12964.22214667123;13020.283884386356;13028.218306901616;13103.60689179326;4616.630669546428;4628.352030715702;4672.065586882637;4684.953150468452;4692.272697731857;10131.746706332253;10135.695929122137;10171.593136274614;10180.949095254624;10218.029287217703;5247.05553000456;5276.657017293864;5316.0696878046965;5316.65441746293;5336.493033161843;4285.314349704033;4787.473377928425;4900.730459824673;4956.975215123974;4965.950340496621;3854.69162467002;3936.822842972073;3984.2230765000545;3988.4615576897327;4048.2510735513433;1142.8342890281028;1157.288267715349;1163.5752950173126;1175.8588969323737;1193.021722187815;801.7835184500152;822.1490757066528;944.9622015119455;949.8290307744672;990.6730778842965;5875.764969401176;5942.286925568959;6024.036154215304;6138.987067133873;6139.6009259398;908.4140729229609;909.2725819344498;927.6474939522938;964.6141543382703;999.8351920013104;3981.064449854282;4144.5233798113;4182.477190351206;4201.264778663722;4224.619784219435;11713.941430292794;11894.940525297317;11918.702032449259;11963.581274655346;12003.579874704343;10629.681109566725;11709.414529273503;11789.764204715857;11802.291862488279;11833.585824496102;8258.174182581693;8615.095414981337;8648.351373647001;8659.09079546651;8669.739907802776;5782.942170578409;6333.968330158504;6347.5556711029785;6416.32628573993;6429.32492088324;10465.581376744845;10787.892121078688;10828.458577071273;10828.5127169276;10923.890761092287;10150.593976240867;10184.490775461345;10688.412137332522;10699.037086662238;10776.515056822618;7839.294463498312;8352.415330926777;8606.956965215133;8761.693340733043;8789.428687135316;8847.601857916537;8850.513221772864;9080.318976076689;9141.040190780139;9141.862872056792;7742.341900938258;7905.249001344863;7961.641726122454;8087.1104178539135;8161.428699990944;7934.682612695427;8002.319802816825;8072.798180045545;8084.231997960184;8225.71210007647;6831.6242606657115;7062.293770622897;7246.89129663066;7411.110666720028;7415.258474152542;5072.315237442898;5086.364204474441;5102.591792656653;5109.131447653834;5260.00059988601;4989.8752531185555;5066.847994560169;5143.099957507496;5261.6579922306455;5280.234366016983 19:23:52 INFO - PROCESS | 11014 | |4;prototype.html;306.3108006984285;321.08650888222996;327.6674175711653;329.3018800143695;333.399880215612;5456.372517160517;5529.557635389169;5581.609287349887;5666.688332141731;5678.6024978251535;5524.944750552493;5545.861353466165;5567;5594.692291923942;5602.579806514517;4398.648108151357;4400.933985990213;4413.6027757501815;4418.668599855007;4425.3140763181755;4323.265044942367;4343.913121737563;4355.303151495761;4406.405135306741;4410.49279332876;4268.38108474271;4288.185244803493;4294.248506511363;4299.828006879721;4334.263175260214;4331.263685173497;4332.501762297329;4365.105153443552;4391.824327026915;4393.824247030115;3759.9436008459893;3827.2154208387215;3846.2499812536566;3847.2882516734494;3896;5337.2527846101575;5381.865453363658;5392.299001129828;5416.945830541723;5417;4925.38432695913;5004.924926126111;5013.022460620181;5016.648834581581;5024.145895197825;5692.743826527816;5820.476157145878;5840.766369345221;5853.560982926255;5871.6183448075735;4163.542010378856;4165.97917010413;4189.413482112506;4195.853145139936;4204.369344598304;6030.638161710312;6064.787732429388;6094.360092190301;6100.23747031621;6105.32841387447;4204.894877628084;4259.488861336659;4279.614834664832;4306.80619372129;4307.375430562551;4260.488741350996;4300.978495107504;4311.417958575615;4338.47938247406;4342.305231162999;4955.677880937727;4973.1297023020825;4983.377077865267;5014.6991180529285;5033.993201359743;5693.971530142406;5902.616329938626;5921.2006379138265;5983.760649573968;6015.729292181775;1487.7396455620224;1492.9402823886924;1493.6340596553914;1509.5924100493025;1517.7799219113167;938.4979036215592;961.3558915526474;966.4826222830089;969.8011907559074;976.1019861727228;5050.116229659795;5074.619403544749;5078.238264260317;5081.8729531762;5107.948920510822;5345.465453454701;5357.866053348619;5400.244920400885;5419.187121931742;5447.319085114361;4828.468868424541;4842.612590992712;5098.311727916758;5098.770555325019;5101.719405432663;2872.669642991041;2891.233823036897;2900.492413827572;2912.199145235077;2912.417516496709;5493.324536016474;5504.80733174337;5523.917141242804;5547.5007249347755;5554.472325129106;5452.754626041838;5598.916016259759;6102.237220347457;6119.592493726468;6147.784827531015;6242.5942313750365;6248.812725582125;6260.593061450991;6294.402031806971;6320.672658741624;5951.791687290924;5990.820275391745;6066.545009124422;6142.32434432221;6156.5980324068505;5991.580589358703;6025.276966764016;6039.818805435844;6052.182955300978;6057.091436284592;5849.356570777126;5980.461758441761;6214.378562143749;6254.155024267761;6304;4209.91580168401;4226.957730422657;4252.9367658085475;4261.296886013897;4265.97867010663;6056.121862329849;6081.331053584191;6094.8171554853425;6107.572469927062;6116.17431646722;5718.513926316144;5832.94167058324;5878.764849406147;5910.231669882888;5987.491063259497;5870.55970802183;5871.6183448075735;5895.734691938787;5898.764049437975;5906.084556893688;5537.972310138423;5790.594658373873;5792.90079807301;5808.21589085485;5809.302883653989;5984.850378740566;5988.940110599013;6005.309389420273;6013;6045.697715114315;5897.198830555451;5904.675242861684;5913.2608423947;5927.347991720993;5934 19:23:52 INFO - PROCESS | 11014 | |5;yui.html;729.2816575672964;779.1312686354717;808.3008197908808;819.5697258939052;824.4064273722919;15124.924375378121;16470.588235294123;16498.175091245434;16542.338306467725;16574.337026518926;14435.855641443582;14535.854641453583;14593.051451655661;14601.92699036506;14607;659.3966520633614;779.6374685771116;799.4963173200875;799.7440818937943;800.3517151107592;774.7482068327816;799.8040480082389;800.2877439079224;801.3629164813987;801.7113839017949;836.753157818442;841.1798496465958;841.4068082002174;849.163573879728;850.1710831938872;841.142035124176;841.2092632925031;843.2326582809644;843.4939036578035;844.5608283692507;610.1305639463747;613.9508839292846;616.2204810914197;616.2481772237862;616.7656290609584;14636.195009274486;14814.259287035713;14816.851831481763;14819.333130009176;14845.628859278497;14800.555983320517;14867.553973380707;14879.776803347959;14978.775318370233;15046.32291546883;12040;12179.939100304531;12194.939025304817;12207.145499815017;12731.872681273162;3870.2259548090215;3872.651461368462;4044.0698639312827;4103.4870641169855;4109.363048727452;69439.95840062403;71128;71791;71923.64038179828;72182.99999999948;11572.884271157182;11767.76464470723;11890.821637675443;12041;12250.264984100982;21049.684254736192;21123;21176.470588235417;21179;21264.149434022776;8498.53258070812;8663.393562450694;8729.61653845016;8740.650373984969;8840.071792461827;4655.930161047587;4741.122892264942;4779.498152693986;4811.591014763784;4927.162382395001;3448.2069124101095;3492.039689085522;3516.701080408143;3564.518789963374;3569.125564236727;606.5420607441354;611.4344231585766;615.301632646949;616.3959319866556;618.9257289125244;653.6372313366128;690.3407246079981;782.2959336597108;788.7318311774025;798.3772657327294;9749.707508774749;9895.455749933823;10129.493525323704;10453.529591168415;10478;597.6533610505896;742.7660287009575;784.6390660296214;788.0385929166408;791.6872835230051;4699.718016918996;4983.750812459362;4989.825356112591;5008.273800298936;5053.2420136979745;106714.46642766736;107831;110190;111847;112411.43794280976;93203;95951.52024239834;97630.51184744031;99786.50106749564;100625.49687251517;70995.64502177559;88015.11984880069;88985.55507222551;89380;89885;8552.786180345542;8816.162464565856;8996.325275604226;9024.819503610022;9257.770966759777;50454.2431863515;50750.492495076054;51137.7443112797;52254;52370.73814630903;71144.28855711376;76571.23428765863;76995.2300476988;78014.21985780296;79267;12428.751424971631;12644.494220231089;12817.743645127232;12903.161294515827;13443.395047222702;13060.673483162996;13493.055486115878;13946.790798137834;13949.581512554234;14045.648858778612;12625.305608191271;12796.3260528871;12934.611961641165;13016.79074924775;13129.671758206121;12945.870541294467;13127.606171814861;13205.603831885059;13212.933935330262;13503.459861605426;11813.940930295294;11900.523979041087;12301.938490307492;12342;12375.257484550955;10110.8483372748;10118.747031324276;10602.25784195099;10660.520276587416;10877.347359158475;9913.355631884193;10639;10719;10957.506912188812;10986.560537578407 19:23:52 INFO - PROCESS | 11014 | __end_tp_report 19:23:52 INFO - PROCESS | 11014 | __start_cc_report 19:23:52 INFO - PROCESS | 11014 | _x_x_mozilla_cycle_collect,345 19:23:52 INFO - PROCESS | 11014 | __end_cc_report 19:23:52 INFO - PROCESS | 11014 | __startTimestamp1465093432286__endTimestamp 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | ------- Summary: start ------- 19:23:52 INFO - PROCESS | 11014 | Number of tests: 6 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | [#0] dojo.html Cycles:165 Average:5399.57 Median:5627.94 stddev:625.74 (11.1%) stddev-sans-first:600.06 19:23:52 INFO - PROCESS | 11014 | Values: 3056.5 3102.7 3113.6 3127.0 3147.9 5322.9 5337.7 5368.9 5390.4 5484.9 5162.1 5322.4 5336.4 5368.1 5373.6 4848.4 4864.4 4945.9 5035.7 5085.1 4872.3 5057.3 5077.2 5082.1 5090.1 4934.0 4953.6 4954.8 4956.1 4963.5 4986.5 4986.7 4988.3 5001.3 5035.3 4954.5 4989.2 4990.1 4995.1 5016.5 5109.7 5309.0 5332.1 5336.1 5395.8 5831.4 5834.9 5846.5 5850.2 5855.5 5698.4 5885.7 5886.4 5917.1 5921.6 5331.7 5348.1 5395.6 5401.0 5407.3 5865.3 5903.4 5906.5 5912.4 5917.1 5541.2 5627.9 5639.4 5661.4 5664.3 5725.2 5832.7 5833.1 5841.9 5885.8 5612.9 5687.1 5785.8 5787.9 5808.4 5726.5 5752.1 5752.6 5765.8 5807.8 4359.8 4386.6 4398.5 4404.2 4412.3 3863.9 3902.8 3910.6 3921.1 3934.7 5651.4 5656.9 5670.3 5683.1 5722.4 5896.1 5932.9 5939.5 5946.1 5954.4 5377.6 5519.9 5542.4 5572.2 5655.6 4984.1 5009.1 5034.5 5037.7 5072.8 6018.2 6036.1 6049.5 6051.4 6062.7 5652.1 5846.6 5848.9 5864.9 5917.3 6043.5 6056.2 6070.3 6083.3 6110.6 5495.1 5797.2 5805.5 5810.5 5814.9 5822.4 5825.2 5871.9 5909.2 5912.7 5725.2 5730.0 5740.5 5741.1 5741.9 5571.5 5604.0 5705.7 5715.4 5726.4 5487.8 5506.6 5542.3 5562.5 5661.5 5675.6 5695.4 5700.1 5735.2 5753.9 5332.8 5556.3 5560.3 5575.3 5634.3 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | [#1] ext.html Cycles:180 Average:59011.73 Median:41433.59 stddev:126947.17 (306.4%) stddev-sans-first:127227.51 19:23:52 INFO - PROCESS | 11014 | Values: 590.3 622.4 630.1 651.3 664.3 41274.4 42490.0 42595.6 45704.8 45705.8 40908.4 41006.4 41428.2 41439.0 41690.4 8382.5 8620.0 8641.0 8641.8 8657.1 8542.7 8562.0 8584.1 8598.7 8613.1 8586.8 8590.7 8605.8 8621.8 8660.4 8521.4 8576.5 8594.1 8601.4 8602.5 8619.3 8634.3 8677.5 8692.7 8706.9 39434.8 39919.0 40018.2 40140.0 40146.0 3447.5 3636.7 3682.1 3698.6 4216.3 29639.6 30019.7 30094.7 30269.5 30972.8 4298.4 4316.4 4327.8 4470.6 4492.4 204785.0 205240.0 206773.0 206859.0 207129.0 35063.6 55179.4 55309.0 55503.4 55664.0 34570.8 35886.1 35948.8 36134.5 36204.0 15514.1 15618.1 15751.5 15841.0 15897.3 13102.9 13395.0 13397.9 13426.1 13449.1 8332.7 8363.4 8405.1 8422.0 8454.6 3256.5 3257.2 3288.7 3309.6 3370.2 970.4 1039.5 1075.1 1092.2 1166.5 32133.2 32566.5 32767.2 32864.3 32937.8 1130.9 1144.0 1155.2 1209.8 1259.5 16976.9 17093.4 17141.5 17310.1 17449.9 596331.0 599082.0 600971.0 608284.0 611022.0 58942.7 59853.4 59966.7 59968.1 59999.0 1341.1 1402.5 1410.6 1434.2 1438.6 30529.0 31815.5 31891.4 31948.4 31977.5 49714.3 50692.0 50855.2 50919.2 50935.2 507811.0 514525.0 517294.0 517704.0 520215.0 27813.4 28303.6 28308.9 28319.7 28364.0 47844.0 47979.3 49423.3 49532.3 49831.8 40692.0 41713.6 41766.4 42005.0 42248.4 41180.0 41265.0 41458.8 41494.6 41648.8 41994.0 42198.4 42971.6 43221.8 44773.3 34644.3 37362.6 37505.4 37895.4 38221.0 28868.3 29048.1 29102.6 29162.6 29310.7 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | [#2] jquery.html Cycles:180 Average:28588.03 Median:404.87 stddev:80320.44 (19838.6%) stddev-sans-first:80517.88 19:23:52 INFO - PROCESS | 11014 | Values: 398.6 403.3 406.4 407.6 423.7 17526.8 19248.8 19313.7 19444.6 20260.5 18920.9 18958.0 19025.5 19581.3 19620.9 5461.3 5494.1 5666.9 5700.9 5701.8 5576.8 5623.7 5624.9 5627.9 5629.8 5384.9 5561.2 5594.1 5611.8 5613.2 5408.9 5482.7 5587.0 5589.2 5591.8 4995.8 5001.3 5027.7 5037.5 5050.3 22559.3 22747.9 22751.3 23231.9 23281.2 2351.8 2434.1 2630.6 2671.7 2672.7 14410.6 14496.6 14633.9 15089.5 15167.0 1936.3 2020.6 2043.4 2055.7 2055.8 81411.0 81483.0 82564.0 83742.0 83884.0 9183.3 9516.1 9828.4 9887.0 10322.1 12596.1 12743.6 12912.9 13087.3 13128.4 5942.7 6027.5 6818.5 7603.1 7768.8 7298.1 7340.8 7360.6 7402.4 7771.8 3949.2 4021.3 4027.4 4129.1 4192.5 1953.5 1988.9 2000.2 2003.8 2017.6 862.8 953.3 1012.2 1023.9 1042.4 17967.6 18447.4 18536.3 18584.8 18678.9 951.8 980.8 1002.4 1031.0 1088.7 10147.7 10377.1 10423.6 10448.5 10459.7 480087.0 488710.0 488816.0 491099.0 491124.0 59802.7 60797.0 62569.0 95342.0 95565.5 970.6 978.5 979.8 983.2 997.2 16511.4 16832.9 16849.0 16895.3 16930.5 43378.3 43801.1 45851.8 46049.3 46165.3 60580.4 61820.7 62566.6 63369.7 63458.0 7600.4 7856.3 8077.1 8393.1 8467.5 12926.2 13387.2 13676.0 13694.0 13742.7 8695.9 11840.2 11964.3 12094.0 12222.2 10948.9 11091.6 11322.0 11468.7 11782.1 11939.8 12375.0 12469.6 12522.7 12746.2 7595.1 7605.8 7879.5 8052.8 8315.8 11132.7 11894.5 12078.9 12165.9 12182.3 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | [#3] mootools.html Cycles:180 Average:6003.01 Median:4739.87 stddev:3476.92 (73.4%) stddev-sans-first:3470.13 19:23:52 INFO - PROCESS | 11014 | Values: 1490.6 1538.0 1694.8 1798.3 1841.3 7897.8 8664.0 8720.7 9395.5 9406.3 8742.4 8980.1 9069.0 9076.4 9096.5 2383.4 2415.3 2420.2 2422.9 2425.6 2410.3 2414.8 2417.1 2418.1 2424.3 2358.9 2397.1 2401.4 2410.9 2426.5 2324.5 2365.5 2368.2 2385.1 2394.2 2355.1 2361.4 2369.1 2371.4 2402.0 8972.1 9075.0 9091.5 9092.4 9148.1 5198.2 5325.7 5330.4 5334.7 5336.2 3360.3 3392.3 3429.5 3469.8 3482.0 1141.5 1157.7 1168.4 1177.9 2191.6 12373.7 12964.2 13020.3 13028.2 13103.6 4616.6 4628.4 4672.1 4685.0 4692.3 10131.7 10135.7 10171.6 10180.9 10218.0 5247.1 5276.7 5316.1 5316.7 5336.5 4285.3 4787.5 4900.7 4957.0 4966.0 3854.7 3936.8 3984.2 3988.5 4048.3 1142.8 1157.3 1163.6 1175.9 1193.0 801.8 822.1 945.0 949.8 990.7 5875.8 5942.3 6024.0 6139.0 6139.6 908.4 909.3 927.6 964.6 999.8 3981.1 4144.5 4182.5 4201.3 4224.6 11713.9 11894.9 11918.7 11963.6 12003.6 10629.7 11709.4 11789.8 11802.3 11833.6 8258.2 8615.1 8648.4 8659.1 8669.7 5782.9 6334.0 6347.6 6416.3 6429.3 10465.6 10787.9 10828.5 10828.5 10923.9 10150.6 10184.5 10688.4 10699.0 10776.5 7839.3 8352.4 8607.0 8761.7 8789.4 8847.6 8850.5 9080.3 9141.0 9141.9 7742.3 7905.2 7961.6 8087.1 8161.4 7934.7 8002.3 8072.8 8084.2 8225.7 6831.6 7062.3 7246.9 7411.1 7415.3 5072.3 5086.4 5102.6 5109.1 5260.0 4989.9 5066.8 5143.1 5261.7 5280.2 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | [#4] prototype.html Cycles:180 Average:4865.96 Median:5454.56 stddev:1442.87 (26.5%) stddev-sans-first:1405.74 19:23:52 INFO - PROCESS | 11014 | Values: 306.3 321.1 327.7 329.3 333.4 5456.4 5529.6 5581.6 5666.7 5678.6 5524.9 5545.9 5567.0 5594.7 5602.6 4398.6 4400.9 4413.6 4418.7 4425.3 4323.3 4343.9 4355.3 4406.4 4410.5 4268.4 4288.2 4294.2 4299.8 4334.3 4331.3 4332.5 4365.1 4391.8 4393.8 3759.9 3827.2 3846.2 3847.3 3896.0 5337.3 5381.9 5392.3 5416.9 5417.0 4925.4 5004.9 5013.0 5016.6 5024.1 5692.7 5820.5 5840.8 5853.6 5871.6 4163.5 4166.0 4189.4 4195.9 4204.4 6030.6 6064.8 6094.4 6100.2 6105.3 4204.9 4259.5 4279.6 4306.8 4307.4 4260.5 4301.0 4311.4 4338.5 4342.3 4955.7 4973.1 4983.4 5014.7 5034.0 5694.0 5902.6 5921.2 5983.8 6015.7 1487.7 1492.9 1493.6 1509.6 1517.8 938.5 961.4 966.5 969.8 976.1 5050.1 5074.6 5078.2 5081.9 5107.9 5345.5 5357.9 5400.2 5419.2 5447.3 4828.5 4842.6 5098.3 5098.8 5101.7 2872.7 2891.2 2900.5 2912.2 2912.4 5493.3 5504.8 5523.9 5547.5 5554.5 5452.8 5598.9 6102.2 6119.6 6147.8 6242.6 6248.8 6260.6 6294.4 6320.7 5951.8 5990.8 6066.5 6142.3 6156.6 5991.6 6025.3 6039.8 6052.2 6057.1 5849.4 5980.5 6214.4 6254.2 6304.0 4209.9 4227.0 4252.9 4261.3 4266.0 6056.1 6081.3 6094.8 6107.6 6116.2 5718.5 5832.9 5878.8 5910.2 5987.5 5870.6 5871.6 5895.7 5898.8 5906.1 5538.0 5790.6 5792.9 5808.2 5809.3 5984.9 5988.9 6005.3 6013.0 6045.7 5897.2 5904.7 5913.3 5927.3 5934.0 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - PROCESS | 11014 | [#5] yui.html Cycles:180 Average:20424.57 Median:4760.31 stddev:29202.67 (613.5%) stddev-sans-first:29247.15 19:23:52 INFO - PROCESS | 11014 | Values: 729.3 779.1 808.3 819.6 824.4 15124.9 16470.6 16498.2 16542.3 16574.3 14435.9 14535.9 14593.1 14601.9 14607.0 659.4 779.6 799.5 799.7 800.4 774.7 799.8 800.3 801.4 801.7 836.8 841.2 841.4 849.2 850.2 841.1 841.2 843.2 843.5 844.6 610.1 614.0 616.2 616.2 616.8 14636.2 14814.3 14816.9 14819.3 14845.6 14800.6 14867.6 14879.8 14978.8 15046.3 12040.0 12179.9 12194.9 12207.1 12731.9 3870.2 3872.7 4044.1 4103.5 4109.4 69440.0 71128.0 71791.0 71923.6 72183.0 11572.9 11767.8 11890.8 12041.0 12250.3 21049.7 21123.0 21176.5 21179.0 21264.1 8498.5 8663.4 8729.6 8740.7 8840.1 4655.9 4741.1 4779.5 4811.6 4927.2 3448.2 3492.0 3516.7 3564.5 3569.1 606.5 611.4 615.3 616.4 618.9 653.6 690.3 782.3 788.7 798.4 9749.7 9895.5 10129.5 10453.5 10478.0 597.7 742.8 784.6 788.0 791.7 4699.7 4983.8 4989.8 5008.3 5053.2 106714.5 107831.0 110190.0 111847.0 112411.4 93203.0 95951.5 97630.5 99786.5 100625.5 70995.6 88015.1 88985.6 89380.0 89885.0 8552.8 8816.2 8996.3 9024.8 9257.8 50454.2 50750.5 51137.7 52254.0 52370.7 71144.3 76571.2 76995.2 78014.2 79267.0 12428.8 12644.5 12817.7 12903.2 13443.4 13060.7 13493.1 13946.8 13949.6 14045.6 12625.3 12796.3 12934.6 13016.8 13129.7 12945.9 13127.6 13205.6 13212.9 13503.5 11813.9 11900.5 12301.9 12342.0 12375.3 10110.8 10118.7 10602.3 10660.5 10877.3 9913.4 10639.0 10719.0 10957.5 10986.6 19:23:52 INFO - PROCESS | 11014 | -------- Summary: end -------- 19:23:52 INFO - PROCESS | 11014 | 19:23:52 INFO - TEST-INFO | 11014: exit 0 19:23:52 INFO - TEST-OK | dromaeo_css | took 1110022ms 19:23:52 INFO - TEST-START | kraken 19:23:52 INFO - operating with platform_type : linux_ 19:23:52 INFO - Initialising browser for kraken test... 19:23:52 INFO - TEST-INFO | started process 11256 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprQvM67/profile http://localhost:41830/getInfo.html) 19:23:57 INFO - PROCESS | 11256 | __metrics Screen width/height:1600/1200 19:23:57 INFO - PROCESS | 11256 | colorDepth:24 19:23:57 INFO - PROCESS | 11256 | Browser inner width/height: 1024/697 19:23:57 INFO - PROCESS | 11256 | __metrics 19:23:58 ERROR - PROCESS | 11256 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 19:23:58 INFO - TEST-INFO | 11256: exit 0 19:23:58 INFO - Browser initialized. 19:23:58 INFO - Running cycle 1/1 for kraken test... 19:23:58 INFO - TEST-INFO | started process 11380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprQvM67/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:24:10 INFO - PROCESS | 11380 | RSS: Main: 167317504 19:24:10 INFO - PROCESS | 11380 | 19:24:13 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41830/tests/kraken/driver.html?testName=audio-beat-detection) 19:24:14 INFO - PROCESS | 11380 | RSS: Main: 172146688 19:24:14 INFO - PROCESS | 11380 | 19:24:16 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41830/tests/kraken/driver.html?testName=audio-dft) 19:24:17 INFO - PROCESS | 11380 | RSS: Main: 171663360 19:24:17 INFO - PROCESS | 11380 | 19:24:20 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41830/tests/kraken/driver.html?testName=audio-fft) 19:24:21 INFO - PROCESS | 11380 | RSS: Main: 166166528 19:24:21 INFO - PROCESS | 11380 | 19:24:23 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41830/tests/kraken/driver.html?testName=audio-oscillator) 19:24:23 INFO - PROCESS | 11380 | RSS: Main: 167206912 19:24:23 INFO - PROCESS | 11380 | 19:24:25 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41830/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:24:26 INFO - PROCESS | 11380 | RSS: Main: 167383040 19:24:26 INFO - PROCESS | 11380 | 19:24:29 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41830/tests/kraken/driver.html?testName=imaging-darkroom) 19:24:30 INFO - PROCESS | 11380 | RSS: Main: 167362560 19:24:30 INFO - PROCESS | 11380 | 19:24:33 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41830/tests/kraken/driver.html?testName=imaging-desaturate) 19:24:34 INFO - PROCESS | 11380 | RSS: Main: 168542208 19:24:34 INFO - PROCESS | 11380 | 19:24:37 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41830/tests/kraken/driver.html?testName=json-parse-financial) 19:24:37 INFO - PROCESS | 11380 | RSS: Main: 168615936 19:24:37 INFO - PROCESS | 11380 | 19:24:39 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41830/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:24:39 INFO - PROCESS | 11380 | RSS: Main: 168509440 19:24:39 INFO - PROCESS | 11380 | 19:24:41 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:24:42 INFO - PROCESS | 11380 | RSS: Main: 168325120 19:24:42 INFO - PROCESS | 11380 | 19:24:44 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:24:45 INFO - PROCESS | 11380 | RSS: Main: 168775680 19:24:45 INFO - PROCESS | 11380 | 19:24:47 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:24:48 INFO - PROCESS | 11380 | RSS: Main: 168960000 19:24:48 INFO - PROCESS | 11380 | 19:24:51 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:24:51 INFO - PROCESS | 11380 | RSS: Main: 168419328 19:24:51 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | Cycle 1(1): loaded http://localhost:41830/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41830/tests/kraken/driver.html?testName=ai-astar) 19:24:53 INFO - PROCESS | 11380 | RSS: Main: 169132032 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | __start_tp_report 19:24:53 INFO - PROCESS | 11380 | _x_x_mozilla_page_load 19:24:53 INFO - PROCESS | 11380 | _x_x_mozilla_page_load_details 19:24:53 INFO - PROCESS | 11380 | |i|pagename|runs| 19:24:53 INFO - PROCESS | 11380 | |0;ai-astar;110;104;110;111;112;112;110;112;111;110 19:24:53 INFO - PROCESS | 11380 | |1;audio-beat-detection;162;132;157;185;125;159;158;125;177;125 19:24:53 INFO - PROCESS | 11380 | |2;audio-dft;162;164;164;162;160;164;166;160;162;160 19:24:53 INFO - PROCESS | 11380 | |3;audio-fft;74;76;75;172;76;75;75;75;168;77 19:24:53 INFO - PROCESS | 11380 | |4;audio-oscillator;86;85;159;85;86;86;141;86;86;147 19:24:53 INFO - PROCESS | 11380 | |5;imaging-gaussian-blur;118;118;117;117;118;118;119;118;125;117 19:24:53 INFO - PROCESS | 11380 | |6;imaging-darkroom;146;148;146;149;148;149;151;147;147;147 19:24:53 INFO - PROCESS | 11380 | |7;imaging-desaturate;96;95;95;96;95;96;95;96;97;95 19:24:53 INFO - PROCESS | 11380 | |8;json-parse-financial;53;53;55;54;52;54;54;53;53;55 19:24:53 INFO - PROCESS | 11380 | |9;json-stringify-tinderbox;63;63;63;63;63;63;63;63;63;63 19:24:53 INFO - PROCESS | 11380 | |10;stanford-crypto-aes;81;81;82;81;82;82;87;81;105;80 19:24:53 INFO - PROCESS | 11380 | |11;stanford-crypto-ccm;131;123;133;127;125;123;128;122;131;133 19:24:53 INFO - PROCESS | 11380 | |12;stanford-crypto-pbkdf2;169;163;164;161;161;158;156;164;163;168 19:24:53 INFO - PROCESS | 11380 | |13;stanford-crypto-sha256-iterative;63;64;68;64;63;64;66;62;62;61 19:24:53 INFO - PROCESS | 11380 | __end_tp_report 19:24:53 INFO - PROCESS | 11380 | __start_cc_report 19:24:53 INFO - PROCESS | 11380 | _x_x_mozilla_cycle_collect,870 19:24:53 INFO - PROCESS | 11380 | __end_cc_report 19:24:53 INFO - PROCESS | 11380 | __startTimestamp1465093493682__endTimestamp 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | ------- Summary: start ------- 19:24:53 INFO - PROCESS | 11380 | Number of tests: 14 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:2.35 (2.1%) stddev-sans-first:2.49 19:24:53 INFO - PROCESS | 11380 | Values: 110.0 104.0 110.0 111.0 112.0 112.0 110.0 112.0 111.0 110.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#1] audio-beat-detection Cycles:10 Average:150.50 Median:158.50 stddev:22.33 (14.1%) stddev-sans-first:23.30 19:24:53 INFO - PROCESS | 11380 | Values: 162.0 132.0 157.0 185.0 125.0 159.0 158.0 125.0 177.0 125.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:2.07 (1.3%) stddev-sans-first:2.19 19:24:53 INFO - PROCESS | 11380 | Values: 162.0 164.0 164.0 162.0 160.0 164.0 166.0 160.0 162.0 160.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.00 stddev:39.92 (53.2%) stddev-sans-first:41.66 19:24:53 INFO - PROCESS | 11380 | Values: 74.0 76.0 75.0 172.0 76.0 75.0 75.0 75.0 168.0 77.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#4] audio-oscillator Cycles:10 Average:104.70 Median:86.00 stddev:30.88 (35.9%) stddev-sans-first:32.00 19:24:53 INFO - PROCESS | 11380 | Values: 86.0 85.0 159.0 85.0 86.0 86.0 141.0 86.0 86.0 147.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:118.00 stddev:2.37 (2.0%) stddev-sans-first:2.51 19:24:53 INFO - PROCESS | 11380 | Values: 118.0 118.0 117.0 117.0 118.0 118.0 119.0 118.0 125.0 117.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.55 (1.0%) stddev-sans-first:1.50 19:24:53 INFO - PROCESS | 11380 | Values: 146.0 148.0 146.0 149.0 148.0 149.0 151.0 147.0 147.0 147.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 19:24:53 INFO - PROCESS | 11380 | Values: 96.0 95.0 95.0 96.0 95.0 96.0 95.0 96.0 97.0 95.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:1.00 19:24:53 INFO - PROCESS | 11380 | Values: 53.0 53.0 55.0 54.0 52.0 54.0 54.0 53.0 53.0 55.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 19:24:53 INFO - PROCESS | 11380 | Values: 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:81.50 stddev:7.55 (9.3%) stddev-sans-first:7.92 19:24:53 INFO - PROCESS | 11380 | Values: 81.0 81.0 82.0 81.0 82.0 82.0 87.0 81.0 105.0 80.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:129.50 stddev:4.25 (3.3%) stddev-sans-first:4.32 19:24:53 INFO - PROCESS | 11380 | Values: 131.0 123.0 133.0 127.0 125.0 123.0 128.0 122.0 131.0 133.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.50 stddev:4.00 (2.4%) stddev-sans-first:3.54 19:24:53 INFO - PROCESS | 11380 | Values: 169.0 163.0 164.0 161.0 161.0 158.0 156.0 164.0 163.0 168.0 19:24:53 INFO - PROCESS | 11380 | 19:24:53 INFO - PROCESS | 11380 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:2.06 (3.2%) stddev-sans-first:2.17 19:24:53 INFO - PROCESS | 11380 | Values: 63.0 64.0 68.0 64.0 63.0 64.0 66.0 62.0 62.0 61.0 19:24:53 INFO - PROCESS | 11380 | -------- Summary: end -------- 19:24:53 INFO - PROCESS | 11380 | 19:24:54 INFO - TEST-INFO | 11380: exit 0 19:24:54 INFO - TEST-OK | kraken | took 61639ms 19:24:54 INFO - SUITE-END | took 1171s 19:24:54 INFO - Completed test suite (00:19:31) 19:24:54 INFO - javascript benchmark 19:24:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7238.488646031771, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3056.465118604244, 3102.7207551320375, 3113.595232619759, 3127.0306205076413, 3147.8740850365984, 5322.866928326794, 5337.6797392156495, 5368.91946620801, 5390.380106287778, 5484.862878428041, 5162.148245539482, 5322.387925388582, 5336.38631557371, 5368.0605893968495, 5373.596980226489, 4848.369711937452, 4864.367632207808, 4945.876353091166, 5035.672681275723, 5085.110105731501, 4872.293517439969, 5057.342545469102, 5077.162268225738, 5082.0598189334905, 5090.109230884599, 4934.037862616809, 4953.578945789613, 4954.801807927679, 4956.107900577895, 4963.528464795839, 4986.526280003394, 4986.725730084844, 4988.276699878534, 5001.349824522788, 5035.320231768737, 4954.479779623124, 4989.226669866177, 4990.051890140898, 4995.075910956485, 5016.523430274118, 5109.744512774347, 5309.0, 5332.146856502941, 5336.119540275849, 5395.811146609889, 5831.358550559435, 5834.941650583482, 5846.473817356458, 5850.239468869062, 5855.502282306009, 5698.373178950312, 5885.676287804212, 5886.381929897342, 5917.14201440793, 5921.585489015771, 5331.680099194061, 5348.117560602496, 5395.5953303502, 5400.972995134999, 5407.297051383334, 5865.296164460292, 5903.3801450847905, 5906.468417842329, 5912.438318359749, 5917.142014407887, 5541.168824676251, 5627.943720562824, 5639.43605639441, 5661.377248502661, 5664.320281566238, 5725.1698503716725, 5832.737526811304, 5833.125031245348, 5841.88316233673, 5885.793997210077, 5612.943870561324, 5687.061634830289, 5785.768569257267, 5787.88424231513, 5808.3901190375245, 5726.484616384545, 5752.079667253303, 5752.568557358215, 5765.827025189251, 5807.82576522705, 4359.825606975749, 4386.583274588909, 4398.538153493901, 4404.185225733249, 4412.338149277634, 3863.903402414906, 3902.824372903226, 3910.589388114264, 3921.0785465415606, 3934.724569280123, 5651.350094739076, 5656.886862262814, 5670.347909990322, 5683.119116536944, 5722.4277572242445, 5896.086106653475, 5932.851678707981, 5939.524838012949, 5946.108083787466, 5954.434328738763, 5377.569794416438, 5519.862003449946, 5542.445755424506, 5572.219889215513, 5655.604107712503, 4984.077945580079, 5009.12340340439, 5034.496550345009, 5037.722925239073, 5072.771725272371, 6018.247719035121, 6036.124761909498, 6049.5462840287155, 6051.364606716319, 6062.727177277033, 5652.0674088774895, 5846.590738648424, 5848.912266316093, 5864.882702346014, 5917.260342457193, 6043.486303664238, 6056.212692350143, 6070.271567411763, 6083.330833608211, 6110.602810817282, 5495.120780675073, 5797.188393624811, 5805.535557155502, 5810.477057064884, 5814.854628634318, 5822.446867547576, 5825.155352473952, 5871.882562348815, 5909.23179986599, 5912.73392697321, 5725.169850371756, 5729.9713501432225, 5740.540756739534, 5741.081426971665, 5741.91387129185, 5571.498565129158, 5603.991281569357, 5705.7432415542235, 5715.428457154251, 5726.398728133486, 5487.807926722546, 5506.64206826555, 5542.307211598551, 5562.527185189304, 5661.490465858093, 5675.631083979528, 5695.430456954271, 5700.059490184073, 5735.168400581976, 5753.9136912945505, 5332.840014799563, 5556.305461817273, 5560.277163968658, 5575.303087114111, 5634.323881134126], "value": 5356.323630470029, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.3152343281791, 622.4460230394948, 630.1367127035961, 651.3486513486514, 664.2593508238313, 41274.38088428672, 42490.0, 42595.57404425955, 45704.771476142654, 45705.77147114268, 40908.38637420441, 41006.38490422638, 41428.17143657125, 41439.0, 41690.37464438036, 8382.538960357193, 8620.0, 8641.049484556694, 8641.783955401133, 8657.134286571323, 8542.658293668277, 8562.0, 8584.0557538671, 8598.742037738877, 8613.095624959384, 8586.828263434727, 8590.699325523608, 8605.827883442358, 8621.827563448727, 8660.437071590326, 8521.44610600309, 8576.485410875368, 8594.054653988056, 8601.354898382615, 8602.526861022641, 8619.310455163572, 8634.26608738258, 8677.479351238882, 8692.695755648585, 8706.869396959051, 39434.80282598597, 39919.0, 40018.199636007404, 40140.0, 40146.0, 3447.465642825366, 3636.7454278200535, 3682.1346983458866, 3698.5746639136305, 4216.325387937946, 29639.55540666892, 30019.69980300191, 30094.699053009408, 30269.545956810445, 30972.8451357744, 4298.3552467130185, 4316.352547117926, 4327.7619730914785, 4470.575295346936, 4492.3710680504755, 204785.0, 205240.0, 206773.0, 206859.0, 207129.0, 35063.64936350655, 55179.44820551743, 55309.0, 55503.44496555063, 55664.0, 34570.82714586411, 35886.1028474285, 35948.82025589855, 36134.457983130276, 36204.0, 15514.146721930401, 15618.141002244758, 15751.527454176394, 15841.049537027815, 15897.28462219203, 13102.868971310356, 13395.0, 13397.866021339856, 13426.127301725357, 13449.058565900294, 8332.708355207538, 8363.372747043995, 8405.117462666332, 8422.0, 8454.619542120496, 3256.544083828266, 3257.2019855135645, 3288.6711328866922, 3309.635940046593, 3370.208001119735, 970.3838062830154, 1039.4750650921237, 1075.1130317488103, 1092.2136062035322, 1166.4867458318313, 32133.19667008297, 32566.511502327485, 32767.18082047968, 32864.342713145605, 32937.83531082329, 1130.9377984210782, 1144.0390072339276, 1155.156735583029, 1209.7822391969355, 1259.5465632372338, 16976.915115424345, 17093.401730939357, 17141.48575542736, 17310.134493275287, 17449.912750436168, 596331.0, 599082.0, 600970.9854352188, 608284.0, 611022.0, 58942.70528647415, 59853.40146598478, 59966.700166498886, 59968.10047849286, 59999.0, 1341.1081630715464, 1402.4600528796486, 1410.6261840612246, 1434.1968497641146, 1438.5971927860217, 30529.0, 31815.522767158047, 31891.36217275595, 31948.36103277875, 31977.520337195427, 49714.254286185016, 50692.0, 50855.23717144172, 50919.236211457595, 50935.235971461196, 507811.0, 514525.0, 517294.0, 517704.0, 520215.0, 27813.44373112567, 28303.575446367908, 28308.858455708414, 28319.716802831706, 28364.0, 47844.0, 47979.28031079467, 49423.25865112098, 49532.25701614551, 49831.750841245565, 40692.0, 41713.58286417097, 41766.37350439685, 42005.0, 42248.36627450529, 41180.0, 41265.0, 41458.79270603628, 41494.58505414907, 41648.79175604103, 41994.0, 42198.367024495274, 42971.57028429676, 43221.78389108034, 44773.32840007338, 34644.307113858085, 37362.626373737, 37505.437418438196, 37895.43156852705, 38221.0, 28868.27829304284, 29048.12855614335, 29102.563461548518, 29162.56256156202, 29310.706892931652], "value": 18817.81910389672, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.6352487473961, 403.34859202388145, 406.39041437843235, 407.5924075924076, 423.7097588152116, 17526.82473175268, 19248.80751192491, 19313.71029434558, 19444.611107777837, 20260.493487662814, 18920.905395473037, 18958.0, 19025.524361890926, 19581.314653987112, 19620.901895490537, 5461.317335333083, 5494.09347457669, 5666.914996275039, 5700.942990570104, 5701.800436984707, 5576.804811831586, 5623.718814059301, 5624.943750562504, 5627.887442251153, 5629.802956896509, 5384.865378365553, 5561.221428999943, 5594.13290939905, 5611.80358687446, 5613.214150019001, 5408.945910540883, 5482.725863706819, 5587.0, 5589.189567512729, 5591.804286849941, 4995.825146119869, 5001.299818025479, 5027.673201241944, 5037.49625037497, 5050.3434553507805, 22559.323220303253, 22747.886260568757, 22751.317460476213, 23231.883840580856, 23281.18515851954, 2351.8000969917407, 2434.075051480444, 2630.6185603087447, 2671.6526851509375, 2672.719364466742, 14410.639734006734, 14496.637584060378, 14633.853661463461, 15089.54731358061, 15167.0, 1936.3416438410868, 2020.605981833543, 2043.427840204745, 2055.650539408304, 2055.7635871874777, 81411.0, 81483.0, 82564.0, 83742.0, 83883.99999999939, 9183.311251656152, 9516.143547080796, 9828.4102953823, 9887.011298870199, 10322.12261957742, 12596.118271721074, 12743.617691469271, 12912.870871291354, 13087.280199589113, 13128.409221585052, 5942.70286485674, 6027.547933904974, 6818.454523638097, 7603.125640551297, 7768.844623107506, 7298.051253337032, 7340.816479588053, 7360.631968401559, 7402.407807375397, 7771.844563108706, 3949.19041596472, 4021.2560676274984, 4027.3958906163725, 4129.12124983132, 4192.538820729717, 1953.540917884296, 1988.9005549722456, 2000.2099170827441, 2003.8096380843797, 2017.6368253714472, 862.7670528957146, 953.3374304858057, 1012.1649639047668, 1023.892491288404, 1042.4244840198194, 17967.64064718698, 18447.354342598213, 18536.258549658134, 18584.81415185858, 18678.906605467157, 951.7906060666643, 980.7646164920368, 1002.3785253143097, 1030.9793804123876, 1088.6516314779353, 10147.746306342253, 10377.066064054272, 10423.5830566778, 10448.477576121164, 10459.68620941373, 480087.0, 488710.0, 488816.0, 491099.0, 491124.0, 59802.700986495656, 60797.0, 62569.0, 95342.0, 95565.52217239009, 970.5632465390744, 978.5400861595039, 979.835132521958, 983.1692220074026, 997.2371136080903, 16511.422100226435, 16832.915835420743, 16849.0, 16895.32418703238, 16930.492085237463, 43378.349324759525, 43801.12397751964, 45851.77074114608, 46049.30926036045, 46165.30752038789, 60580.39419605924, 61820.69089654523, 62566.558839264275, 63369.68315158579, 63458.0, 7600.3539699126195, 7856.25365590268, 8077.111517733162, 8393.118722534045, 8467.534285614103, 12926.224426534438, 13387.196768193939, 13676.042677012514, 13694.041417101105, 13742.725145497234, 8695.86956195645, 11840.171188016757, 11964.2651815488, 12094.0, 12222.20555663879, 10948.945255273673, 11091.5563377464, 11322.0, 11468.655940321803, 11782.116341274268, 11939.820902686293, 12375.0, 12469.563565275172, 12522.686932826753, 12746.235225886476, 7595.126560445619, 7605.847883042419, 7879.487833290817, 8052.798680033046, 8315.83368332642, 11132.721681958015, 11894.524219031488, 12078.879211207775, 12165.939170304446, 12182.329971851634], "value": 9059.874495651307, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1490.5528341497552, 1538.0002998051266, 1694.7627332173497, 1798.3294807211298, 1841.327915310912, 7897.763067107996, 8663.95668021659, 8720.651173953034, 9395.483248421326, 9406.341556091067, 8742.431741936769, 8980.101989801016, 9069.047749986255, 9076.410033347827, 9096.499692516909, 2383.368407372048, 2415.3237093613816, 2420.213430635051, 2422.878856057199, 2425.6361545768186, 2410.337157281744, 2414.770596793302, 2417.105670901769, 2418.093215044358, 2424.260600516842, 2358.870262135582, 2397.1010870923405, 2401.435662619283, 2410.939186757821, 2426.5389575980594, 2324.476992676651, 2365.491419344832, 2368.1829768729763, 2385.1294277588727, 2394.150076722756, 2355.0815182079004, 2361.362432143329, 2369.1115831563166, 2371.4071482129466, 2401.9639705404256, 8972.057933916974, 9075.0, 9091.499967501784, 9092.363534552584, 9148.085191480865, 5198.220266959948, 5325.707086110262, 5330.413654498002, 5334.67991920486, 5336.17289320156, 3360.260742636616, 3392.3215356928713, 3429.451287793966, 3469.8265086745814, 3482.04243832948, 1141.4806263150247, 1157.6700640317554, 1168.3924359333184, 1177.941102944858, 2191.605511008018, 12373.69065773354, 12964.22214667123, 13020.283884386356, 13028.218306901616, 13103.60689179326, 4616.630669546428, 4628.352030715702, 4672.065586882637, 4684.953150468452, 4692.272697731857, 10131.746706332253, 10135.695929122137, 10171.593136274614, 10180.949095254624, 10218.029287217703, 5247.05553000456, 5276.657017293864, 5316.0696878046965, 5316.65441746293, 5336.493033161843, 4285.314349704033, 4787.473377928425, 4900.730459824673, 4956.975215123974, 4965.950340496621, 3854.69162467002, 3936.822842972073, 3984.2230765000545, 3988.4615576897327, 4048.2510735513433, 1142.8342890281028, 1157.288267715349, 1163.5752950173126, 1175.8588969323737, 1193.021722187815, 801.7835184500152, 822.1490757066528, 944.9622015119455, 949.8290307744672, 990.6730778842965, 5875.764969401176, 5942.286925568959, 6024.036154215304, 6138.987067133873, 6139.6009259398, 908.4140729229609, 909.2725819344498, 927.6474939522938, 964.6141543382703, 999.8351920013104, 3981.064449854282, 4144.5233798113, 4182.477190351206, 4201.264778663722, 4224.619784219435, 11713.941430292794, 11894.940525297317, 11918.702032449259, 11963.581274655346, 12003.579874704343, 10629.681109566725, 11709.414529273503, 11789.764204715857, 11802.291862488279, 11833.585824496102, 8258.174182581693, 8615.095414981337, 8648.351373647001, 8659.09079546651, 8669.739907802776, 5782.942170578409, 6333.968330158504, 6347.5556711029785, 6416.32628573993, 6429.32492088324, 10465.581376744845, 10787.892121078688, 10828.458577071273, 10828.5127169276, 10923.890761092287, 10150.593976240867, 10184.490775461345, 10688.412137332522, 10699.037086662238, 10776.515056822618, 7839.294463498312, 8352.415330926777, 8606.956965215133, 8761.693340733043, 8789.428687135316, 8847.601857916537, 8850.513221772864, 9080.318976076689, 9141.040190780139, 9141.862872056792, 7742.341900938258, 7905.249001344863, 7961.641726122454, 8087.1104178539135, 8161.428699990944, 7934.682612695427, 8002.319802816825, 8072.798180045545, 8084.231997960184, 8225.71210007647, 6831.6242606657115, 7062.293770622897, 7246.89129663066, 7411.110666720028, 7415.258474152542, 5072.315237442898, 5086.364204474441, 5102.591792656653, 5109.131447653834, 5260.00059988601, 4989.8752531185555, 5066.847994560169, 5143.099957507496, 5261.6579922306455, 5280.234366016983], "value": 4775.303536804301, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.3108006984285, 321.08650888222996, 327.6674175711653, 329.3018800143695, 333.399880215612, 5456.372517160517, 5529.557635389169, 5581.609287349887, 5666.688332141731, 5678.6024978251535, 5524.944750552493, 5545.861353466165, 5567.0, 5594.692291923942, 5602.579806514517, 4398.648108151357, 4400.933985990213, 4413.6027757501815, 4418.668599855007, 4425.3140763181755, 4323.265044942367, 4343.913121737563, 4355.303151495761, 4406.405135306741, 4410.49279332876, 4268.38108474271, 4288.185244803493, 4294.248506511363, 4299.828006879721, 4334.263175260214, 4331.263685173497, 4332.501762297329, 4365.105153443552, 4391.824327026915, 4393.824247030115, 3759.9436008459893, 3827.2154208387215, 3846.2499812536566, 3847.2882516734494, 3896.0, 5337.2527846101575, 5381.865453363658, 5392.299001129828, 5416.945830541723, 5417.0, 4925.38432695913, 5004.924926126111, 5013.022460620181, 5016.648834581581, 5024.145895197825, 5692.743826527816, 5820.476157145878, 5840.766369345221, 5853.560982926255, 5871.6183448075735, 4163.542010378856, 4165.97917010413, 4189.413482112506, 4195.853145139936, 4204.369344598304, 6030.638161710312, 6064.787732429388, 6094.360092190301, 6100.23747031621, 6105.32841387447, 4204.894877628084, 4259.488861336659, 4279.614834664832, 4306.80619372129, 4307.375430562551, 4260.488741350996, 4300.978495107504, 4311.417958575615, 4338.47938247406, 4342.305231162999, 4955.677880937727, 4973.1297023020825, 4983.377077865267, 5014.6991180529285, 5033.993201359743, 5693.971530142406, 5902.616329938626, 5921.2006379138265, 5983.760649573968, 6015.729292181775, 1487.7396455620224, 1492.9402823886924, 1493.6340596553914, 1509.5924100493025, 1517.7799219113167, 938.4979036215592, 961.3558915526474, 966.4826222830089, 969.8011907559074, 976.1019861727228, 5050.116229659795, 5074.619403544749, 5078.238264260317, 5081.8729531762, 5107.948920510822, 5345.465453454701, 5357.866053348619, 5400.244920400885, 5419.187121931742, 5447.319085114361, 4828.468868424541, 4842.612590992712, 5098.311727916758, 5098.770555325019, 5101.719405432663, 2872.669642991041, 2891.233823036897, 2900.492413827572, 2912.199145235077, 2912.417516496709, 5493.324536016474, 5504.80733174337, 5523.917141242804, 5547.5007249347755, 5554.472325129106, 5452.754626041838, 5598.916016259759, 6102.237220347457, 6119.592493726468, 6147.784827531015, 6242.5942313750365, 6248.812725582125, 6260.593061450991, 6294.402031806971, 6320.672658741624, 5951.791687290924, 5990.820275391745, 6066.545009124422, 6142.32434432221, 6156.5980324068505, 5991.580589358703, 6025.276966764016, 6039.818805435844, 6052.182955300978, 6057.091436284592, 5849.356570777126, 5980.461758441761, 6214.378562143749, 6254.155024267761, 6304.0, 4209.91580168401, 4226.957730422657, 4252.9367658085475, 4261.296886013897, 4265.97867010663, 6056.121862329849, 6081.331053584191, 6094.8171554853425, 6107.572469927062, 6116.17431646722, 5718.513926316144, 5832.94167058324, 5878.764849406147, 5910.231669882888, 5987.491063259497, 5870.55970802183, 5871.6183448075735, 5895.734691938787, 5898.764049437975, 5906.084556893688, 5537.972310138423, 5790.594658373873, 5792.90079807301, 5808.21589085485, 5809.302883653989, 5984.850378740566, 5988.940110599013, 6005.309389420273, 6013.0, 6045.697715114315, 5897.198830555451, 5904.675242861684, 5913.2608423947, 5927.347991720993, 5934.0], "value": 4398.286309991338, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.2816575672964, 779.1312686354717, 808.3008197908808, 819.5697258939052, 824.4064273722919, 15124.924375378121, 16470.588235294123, 16498.175091245434, 16542.338306467725, 16574.337026518926, 14435.855641443582, 14535.854641453583, 14593.051451655661, 14601.92699036506, 14607.0, 659.3966520633614, 779.6374685771116, 799.4963173200875, 799.7440818937943, 800.3517151107592, 774.7482068327816, 799.8040480082389, 800.2877439079224, 801.3629164813987, 801.7113839017949, 836.753157818442, 841.1798496465958, 841.4068082002174, 849.163573879728, 850.1710831938872, 841.142035124176, 841.2092632925031, 843.2326582809644, 843.4939036578035, 844.5608283692507, 610.1305639463747, 613.9508839292846, 616.2204810914197, 616.2481772237862, 616.7656290609584, 14636.195009274486, 14814.259287035713, 14816.851831481763, 14819.333130009176, 14845.628859278497, 14800.555983320517, 14867.553973380707, 14879.776803347959, 14978.775318370233, 15046.32291546883, 12040.0, 12179.939100304531, 12194.939025304817, 12207.145499815017, 12731.872681273162, 3870.2259548090215, 3872.651461368462, 4044.0698639312827, 4103.4870641169855, 4109.363048727452, 69439.95840062403, 71128.0, 71791.0, 71923.64038179828, 72182.99999999948, 11572.884271157182, 11767.76464470723, 11890.821637675443, 12041.0, 12250.264984100982, 21049.684254736192, 21123.0, 21176.470588235417, 21179.0, 21264.149434022776, 8498.53258070812, 8663.393562450694, 8729.61653845016, 8740.650373984969, 8840.071792461827, 4655.930161047587, 4741.122892264942, 4779.498152693986, 4811.591014763784, 4927.162382395001, 3448.2069124101095, 3492.039689085522, 3516.701080408143, 3564.518789963374, 3569.125564236727, 606.5420607441354, 611.4344231585766, 615.301632646949, 616.3959319866556, 618.9257289125244, 653.6372313366128, 690.3407246079981, 782.2959336597108, 788.7318311774025, 798.3772657327294, 9749.707508774749, 9895.455749933823, 10129.493525323704, 10453.529591168415, 10478.0, 597.6533610505896, 742.7660287009575, 784.6390660296214, 788.0385929166408, 791.6872835230051, 4699.718016918996, 4983.750812459362, 4989.825356112591, 5008.273800298936, 5053.2420136979745, 106714.46642766736, 107831.0, 110190.0, 111847.0, 112411.43794280976, 93203.0, 95951.52024239834, 97630.51184744031, 99786.50106749564, 100625.49687251517, 70995.64502177559, 88015.11984880069, 88985.55507222551, 89380.0, 89885.0, 8552.786180345542, 8816.162464565856, 8996.325275604226, 9024.819503610022, 9257.770966759777, 50454.2431863515, 50750.492495076054, 51137.7443112797, 52254.0, 52370.73814630903, 71144.28855711376, 76571.23428765863, 76995.2300476988, 78014.21985780296, 79267.0, 12428.751424971631, 12644.494220231089, 12817.743645127232, 12903.161294515827, 13443.395047222702, 13060.673483162996, 13493.055486115878, 13946.790798137834, 13949.581512554234, 14045.648858778612, 12625.305608191271, 12796.3260528871, 12934.611961641165, 13016.79074924775, 13129.671758206121, 12945.870541294467, 13127.606171814861, 13205.603831885059, 13212.933935330262, 13503.459861605426, 11813.940930295294, 11900.523979041087, 12301.938490307492, 12342.0, 12375.257484550955, 10110.8483372748, 10118.747031324276, 10602.25784195099, 10660.520276587416, 10877.347359158475, 9913.355631884193, 10639.0, 10719.0, 10957.506912188812, 10986.560537578407], "value": 7499.027176894494, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 104.0, 110.0, 111.0, 112.0, 112.0, 110.0, 112.0, 111.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 132.0, 157.0, 185.0, 125.0, 159.0, 158.0, 125.0, 177.0, 125.0], "value": 150.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 164.0, 162.0, 160.0, 164.0, 166.0, 160.0, 162.0, 160.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 172.0, 76.0, 75.0, 75.0, 75.0, 168.0, 77.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 159.0, 85.0, 86.0, 86.0, 141.0, 86.0, 86.0, 147.0], "value": 104.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 119.0, 118.0, 125.0, 117.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 149.0, 148.0, 149.0, 151.0, 147.0, 147.0, 147.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 97.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 54.0, 52.0, 54.0, 54.0, 53.0, 53.0, 55.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 82.0, 81.0, 82.0, 82.0, 87.0, 81.0, 105.0, 80.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 123.0, 133.0, 127.0, 125.0, 123.0, 128.0, 122.0, 131.0, 133.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 163.0, 164.0, 161.0, 161.0, 158.0, 156.0, 164.0, 163.0, 168.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 68.0, 64.0, 63.0, 64.0, 66.0, 62.0, 62.0, 61.0], "value": 63.7, "unit": "score"}]}]} 19:24:54 INFO - Return code: 0 19:24:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:24:54 ERROR - # TBPL SUCCESS # 19:24:54 INFO - Running post-action listener: _resource_record_post_action 19:24:54 INFO - ##### 19:24:54 INFO - ##### Finished run-tests step (success) 19:24:54 INFO - ##### 19:24:54 INFO - Running post-run listener: _resource_record_post_run 19:24:55 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 293351424; Read time: 32; Write time: 146856 19:24:55 INFO - TinderboxPrint: CPU usage
13.5% 19:24:55 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 32 19:24:55 INFO - TinderboxPrint: I/O write bytes / time
293,351,424 / 146,856 19:24:55 INFO - TinderboxPrint: CPU idle
8,216.1 (86.5%) 19:24:55 INFO - TinderboxPrint: CPU user
1,226.0 (12.9%) 19:24:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:24:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 1425408; Read time: 0; Write time: 52 19:24:55 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 291926016; Read time: 32; Write time: 146804 19:24:55 INFO - Running post-run listener: _upload_blobber_files 19:24:55 INFO - Blob upload gear active. 19:24:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:24:55 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 19:24:55 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'] 19:24:55 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 19:24:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:24:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:24:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:24:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:57 INFO - (blobuploader) - INFO - Done attempting. 19:24:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:24:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:25:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:01 INFO - (blobuploader) - INFO - Done attempting. 19:25:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:25:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:25:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:25:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:25:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:25:02 INFO - (blobuploader) - INFO - Done attempting. 19:25:02 INFO - (blobuploader) - INFO - Iteration through files over. 19:25:02 INFO - Return code: 0 19:25:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:25:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:25:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c9dbd71e58c21bfb2305181993f76b8d07dd8df0ea1710610a058ef6d4033f9e7d278c7125e62ff14af73dae0dc854ccaeb212ee81d73475812eaa70b3ebf816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8580232839cd10606c117bd34fb49830814669acd82e1033c01195cbfa00ee887b8dc079a34f08060779cd564328e3f3aad5280073c1b5f05c51d57aba165ea5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9a316ea057a2c11f9deca612d06e6318620da7d82aafaddc5fb8a2c81ff3a6e03c718a0e582d7fdc61aa1012d12b5ee7a563feda23ea6ad282233520561e160"} 19:25:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:25:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:25:02 INFO - Contents: 19:25:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c9dbd71e58c21bfb2305181993f76b8d07dd8df0ea1710610a058ef6d4033f9e7d278c7125e62ff14af73dae0dc854ccaeb212ee81d73475812eaa70b3ebf816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8580232839cd10606c117bd34fb49830814669acd82e1033c01195cbfa00ee887b8dc079a34f08060779cd564328e3f3aad5280073c1b5f05c51d57aba165ea5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9a316ea057a2c11f9deca612d06e6318620da7d82aafaddc5fb8a2c81ff3a6e03c718a0e582d7fdc61aa1012d12b5ee7a563feda23ea6ad282233520561e160"} 19:25:02 INFO - Running post-run listener: copy_logs_to_upload_dir 19:25:02 INFO - Copying logs to upload dir... 19:25:02 INFO - mkdir: /builds/slave/test/build/upload/logs 19:25:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.367957 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-04 19:25:02.267636) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 19:25:02.270936) ========= 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=6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c9dbd71e58c21bfb2305181993f76b8d07dd8df0ea1710610a058ef6d4033f9e7d278c7125e62ff14af73dae0dc854ccaeb212ee81d73475812eaa70b3ebf816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8580232839cd10606c117bd34fb49830814669acd82e1033c01195cbfa00ee887b8dc079a34f08060779cd564328e3f3aad5280073c1b5f05c51d57aba165ea5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9a316ea057a2c11f9deca612d06e6318620da7d82aafaddc5fb8a2c81ff3a6e03c718a0e582d7fdc61aa1012d12b5ee7a563feda23ea6ad282233520561e160"} build_url:https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/AKD7LRIvRdOJYoNis6ZYCQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c9dbd71e58c21bfb2305181993f76b8d07dd8df0ea1710610a058ef6d4033f9e7d278c7125e62ff14af73dae0dc854ccaeb212ee81d73475812eaa70b3ebf816", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8580232839cd10606c117bd34fb49830814669acd82e1033c01195cbfa00ee887b8dc079a34f08060779cd564328e3f3aad5280073c1b5f05c51d57aba165ea5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d9a316ea057a2c11f9deca612d06e6318620da7d82aafaddc5fb8a2c81ff3a6e03c718a0e582d7fdc61aa1012d12b5ee7a563feda23ea6ad282233520561e160"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 19:25:02.295986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:25:02.296320) ========= 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=6dbc10da6d83fee962c624be000001a8-1465082015.581877-729881716 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 19:25:02.382523) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 19:25:02.382863) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 19:25:02.383164) ========= ========= Total master_lag: 0.16 =========