builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1464930033.2 results: success (0) buildid: 20160602193138 builduid: 9a59e4d19a5a4d89b490b9986f12fa77 revision: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.199738) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.200143) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.200415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.225293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.225637) ========= 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=aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 22:00:33-- 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% 70.2M=0s 2016-06-02 22:00:33 (70.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094110 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.333236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.333531) ========= 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=aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023515 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.384066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:33.384375) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev c5c9bc65a408fa0c26ec742c0950b6fc484cb635 --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 c5c9bc65a408fa0c26ec742c0950b6fc484cb635 --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=aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 22:00:33,650 truncating revision to first 12 chars 2016-06-02 22:00:33,650 Setting DEBUG logging. 2016-06-02 22:00:33,650 attempt 1/10 2016-06-02 22:00:33,650 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/c5c9bc65a408?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 22:00:34,084 unpacking tar archive at: mozilla-inbound-c5c9bc65a408/testing/mozharness/ program finished with exit code 0 elapsedTime=0.772756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:34.173841) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:34.174150) ========= script_repo_revision: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:34.174591) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:34.174876) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 22:00:34.192934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-06-02 22:00:34.193214) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:00:34 INFO - MultiFileLogger online at 20160602 22:00:34 in /builds/slave/test 22:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:00:34 INFO - {'append_to_log': False, 22:00:34 INFO - 'base_work_dir': '/builds/slave/test', 22:00:34 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:00:34 INFO - 'branch': 'Mozilla-Inbound', 22:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 22:00:34 INFO - 'config_files': ('talos/linux_config.py',), 22:00:34 INFO - 'default_actions': ('clobber', 22:00:34 INFO - 'read-buildbot-config', 22:00:34 INFO - 'download-and-extract', 22:00:34 INFO - 'populate-webroot', 22:00:34 INFO - 'create-virtualenv', 22:00:34 INFO - 'install', 22:00:34 INFO - 'run-tests'), 22:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:00:34 INFO - 'download_minidump_stackwalk': True, 22:00:34 INFO - 'download_symbols': 'ondemand', 22:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 22:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 22:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:00:34 INFO - 'installer_path': 'installer.exe', 22:00:34 INFO - 'log_level': 'info', 22:00:34 INFO - 'log_name': 'talos', 22:00:34 INFO - 'log_to_console': True, 22:00:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:00:34 INFO - 'opt_config_files': (), 22:00:34 INFO - 'pip_index': False, 22:00:34 INFO - 'sps_profile': False, 22:00:34 INFO - 'sps_profile_interval': 0, 22:00:34 INFO - 'suite': 'dromaeojs', 22:00:34 INFO - 'system_bits': '32', 22:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:00:34 INFO - 'title': 'talos-linux64-ix-037', 22:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:00:34 INFO - 'use_talos_json': True, 22:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:00:34 INFO - 'work_dir': 'build'} 22:00:34 INFO - ##### 22:00:34 INFO - ##### Running clobber step. 22:00:34 INFO - ##### 22:00:34 INFO - Running pre-action listener: _resource_record_pre_action 22:00:34 INFO - Running main action method: clobber 22:00:34 INFO - rmtree: /builds/slave/test/build 22:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:00:35 INFO - Running post-action listener: _resource_record_post_action 22:00:35 INFO - ##### 22:00:35 INFO - ##### Finished clobber step (success) 22:00:35 INFO - ##### 22:00:35 INFO - ##### 22:00:35 INFO - ##### Running read-buildbot-config step. 22:00:35 INFO - ##### 22:00:35 INFO - Running pre-action listener: _resource_record_pre_action 22:00:35 INFO - Running main action method: read_buildbot_config 22:00:35 INFO - Using buildbot properties: 22:00:35 INFO - { 22:00:35 INFO - "project": "", 22:00:35 INFO - "product": "firefox", 22:00:35 INFO - "script_repo_revision": "production", 22:00:35 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:00:35 INFO - "repository": "", 22:00:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:00:35 INFO - "buildid": "20160602193138", 22:00:35 INFO - "pgo_build": "True", 22:00:35 INFO - "basedir": "/builds/slave/test", 22:00:35 INFO - "buildnumber": 1109, 22:00:35 INFO - "slavename": "talos-linux64-ix-037", 22:00:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:00:35 INFO - "platform": "ubuntu64_hw", 22:00:35 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:00:35 INFO - "branch": "mozilla-inbound", 22:00:35 INFO - "repo_path": "integration/mozilla-inbound", 22:00:35 INFO - "revision": "c5c9bc65a408fa0c26ec742c0950b6fc484cb635", 22:00:35 INFO - "stage_platform": "linux64-pgo", 22:00:35 INFO - "builduid": "9a59e4d19a5a4d89b490b9986f12fa77", 22:00:35 INFO - "slavebuilddir": "test" 22:00:35 INFO - } 22:00:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:00:35 INFO - Running post-action listener: _resource_record_post_action 22:00:35 INFO - ##### 22:00:35 INFO - ##### Finished read-buildbot-config step (success) 22:00:35 INFO - ##### 22:00:35 INFO - ##### 22:00:35 INFO - ##### Running download-and-extract step. 22:00:35 INFO - ##### 22:00:35 INFO - Running pre-action listener: _resource_record_pre_action 22:00:35 INFO - Running main action method: download_and_extract 22:00:35 INFO - mkdir: /builds/slave/test/build/tests 22:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:35 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:43 INFO - Downloaded 1361 bytes. 22:00:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:00:43 INFO - Using the following test package requirements: 22:00:43 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:00:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:00:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'jsshell-linux-x86_64.zip'], 22:00:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:00:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:00:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:00:43 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:00:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:00:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:00:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:00:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:00:43 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')]} 22:00:43 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:46 INFO - Downloaded 24424251 bytes. 22:00:46 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'] 22:00:46 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 22:00:47 INFO - Return code: 0 22:00:47 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 22:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:47 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:51 INFO - Downloaded 24424251 bytes. 22:00:51 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'] 22:00:51 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 22:00:52 INFO - Return code: 0 22:00:52 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')]} 22:00:52 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:00:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:00:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:54 INFO - Downloaded 10833369 bytes. 22:00:54 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'] 22:00:54 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 22:00:55 INFO - Return code: 0 22:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:00:55 INFO - https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:00:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cN-KpsgvRQKyhxA3iZAElA/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 22:00:59 INFO - Downloaded 59144868 bytes. 22:00:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:59 INFO - mkdir: /builds/slave/test/properties 22:00:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:00:59 INFO - Writing to file /builds/slave/test/properties/build_url 22:00:59 INFO - Contents: 22:00:59 INFO - build_url:https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:00:59 INFO - Running post-action listener: _resource_record_post_action 22:00:59 INFO - Running post-action listener: set_extra_try_arguments 22:00:59 INFO - ##### 22:00:59 INFO - ##### Finished download-and-extract step (success) 22:00:59 INFO - ##### 22:00:59 INFO - ##### 22:00:59 INFO - ##### Running populate-webroot step. 22:00:59 INFO - ##### 22:00:59 INFO - Running pre-action listener: _resource_record_pre_action 22:00:59 INFO - Running main action method: populate_webroot 22:00:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:00:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:00:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:00:59 INFO - '--tppagecycles', 22:00:59 INFO - '7'], 22:00:59 INFO - 'tests': ['tsvgm']}}, 22:00:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:00:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:00:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:00:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:00:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:00:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['damp', 'tps']}, 22:00:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'tests': ['damp', 'tps']}, 22:00:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['dromaeo_dom']}, 22:00:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:00:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['a11yr', 22:00:59 INFO - 'ts_paint', 22:00:59 INFO - 'tpaint', 22:00:59 INFO - 'sessionrestore', 22:00:59 INFO - 'sessionrestore_no_auto_restore', 22:00:59 INFO - 'tabpaint']}, 22:00:59 INFO - 'other-e10s': {'tests': ['a11yr', 22:00:59 INFO - 'ts_paint', 22:00:59 INFO - 'tpaint', 22:00:59 INFO - 'sessionrestore', 22:00:59 INFO - 'sessionrestore_no_auto_restore', 22:00:59 INFO - 'tabpaint']}, 22:00:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:00:59 INFO - 'ts_paint', 22:00:59 INFO - 'tpaint', 22:00:59 INFO - 'sessionrestore', 22:00:59 INFO - 'sessionrestore_no_auto_restore', 22:00:59 INFO - 'tabpaint']}, 22:00:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:00:59 INFO - 'ts_paint', 22:00:59 INFO - 'tpaint', 22:00:59 INFO - 'sessionrestore', 22:00:59 INFO - 'sessionrestore_no_auto_restore', 22:00:59 INFO - 'tabpaint']}, 22:00:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['a11yr', 22:00:59 INFO - 'ts_paint', 22:00:59 INFO - 'tpaint', 22:00:59 INFO - 'sessionrestore', 22:00:59 INFO - 'sessionrestore_no_auto_restore', 22:00:59 INFO - 'tabpaint']}, 22:00:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['a11yr', 22:00:59 INFO - 'ts_paint', 22:00:59 INFO - 'tpaint', 22:00:59 INFO - 'sessionrestore', 22:00:59 INFO - 'sessionrestore_no_auto_restore', 22:00:59 INFO - 'tabpaint']}, 22:00:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['tsvgx', 22:00:59 INFO - 'tsvgr_opacity', 22:00:59 INFO - 'tart', 22:00:59 INFO - 'tscrollx', 22:00:59 INFO - 'cart']}, 22:00:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:00:59 INFO - 'tsvgr_opacity', 22:00:59 INFO - 'tart', 22:00:59 INFO - 'tscrollx', 22:00:59 INFO - 'cart']}, 22:00:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'talos_options': ['--disable-e10s'], 22:00:59 INFO - 'tests': ['tp5o']}, 22:00:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'tests': ['tp5o']}, 22:00:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'talos_options': ['--disable-e10s', 22:00:59 INFO - '--xperf_path', 22:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:59 INFO - 'tests': ['tp5n']}, 22:00:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:00:59 INFO - 'talos_options': ['--xperf_path', 22:00:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:00:59 INFO - 'tests': ['tp5n']}}, 22:00:59 INFO - 'talos.zip': {'path': '', 22:00:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:00:59 INFO - Running post-action listener: _resource_record_post_action 22:00:59 INFO - ##### 22:00:59 INFO - ##### Finished populate-webroot step (success) 22:00:59 INFO - ##### 22:00:59 INFO - ##### 22:00:59 INFO - ##### Running create-virtualenv step. 22:00:59 INFO - ##### 22:00:59 INFO - Running pre-action listener: _resource_record_pre_action 22:00:59 INFO - Running main action method: create_virtualenv 22:00:59 INFO - Creating virtualenv /builds/slave/test/build/venv 22:00:59 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 22:00:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:00 INFO - Using real prefix '/usr' 22:01:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:00 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:02 INFO - Installing pip.................done. 22:01:02 INFO - Return code: 0 22:01:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01:02 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')]} 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:02 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')]} 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:02 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:02 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 22:01:02 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 22:01:02 INFO - Using env: {'DISPLAY': ':0', 22:01:02 INFO - 'HOME': '/home/cltbld', 22:01:02 INFO - 'LANG': 'en_US.UTF-8', 22:01:02 INFO - 'LANGUAGE': 'en_US:en', 22:01:02 INFO - 'LOGNAME': 'cltbld', 22:01:02 INFO - 'MAIL': '/var/mail/cltbld', 22:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:02 INFO - 'MOZ_NO_REMOTE': '1', 22:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:02 INFO - 'NO_EM_RESTART': '1', 22:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:02 INFO - 'PWD': '/builds/slave/test', 22:01:02 INFO - 'SHELL': '/bin/bash', 22:01:02 INFO - 'SHLVL': '1', 22:01:02 INFO - 'TERM': 'linux', 22:01:02 INFO - 'TMOUT': '86400', 22:01:02 INFO - 'USER': 'cltbld', 22:01:02 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:02 INFO - '_': '/tools/buildbot/bin/python'} 22:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:02 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 22:01:02 INFO - Cleaning up... 22:01:02 INFO - Return code: 0 22:01:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01:02 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')]} 22:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:02 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')]} 22:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:02 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:02 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 22:01:02 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 22:01:02 INFO - Using env: {'DISPLAY': ':0', 22:01:02 INFO - 'HOME': '/home/cltbld', 22:01:02 INFO - 'LANG': 'en_US.UTF-8', 22:01:02 INFO - 'LANGUAGE': 'en_US:en', 22:01:02 INFO - 'LOGNAME': 'cltbld', 22:01:02 INFO - 'MAIL': '/var/mail/cltbld', 22:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:02 INFO - 'MOZ_NO_REMOTE': '1', 22:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:02 INFO - 'NO_EM_RESTART': '1', 22:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:02 INFO - 'PWD': '/builds/slave/test', 22:01:02 INFO - 'SHELL': '/bin/bash', 22:01:02 INFO - 'SHLVL': '1', 22:01:02 INFO - 'TERM': 'linux', 22:01:02 INFO - 'TMOUT': '86400', 22:01:02 INFO - 'USER': 'cltbld', 22:01:02 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:02 INFO - '_': '/tools/buildbot/bin/python'} 22:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:03 INFO - Downloading/unpacking psutil>=3.1.1 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:05 INFO - Installing collected packages: psutil 22:01:05 INFO - Running setup.py install for psutil 22:01:05 INFO - building 'psutil._psutil_linux' extension 22:01:05 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 22:01:05 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 22:01:05 INFO - building 'psutil._psutil_posix' extension 22:01:05 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 22:01:05 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 22:01:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:05 INFO - Successfully installed psutil 22:01:05 INFO - Cleaning up... 22:01:05 INFO - Return code: 0 22:01:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01:05 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')]} 22:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:05 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')]} 22:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:05 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:05 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 22:01:05 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 22:01:05 INFO - Using env: {'DISPLAY': ':0', 22:01:05 INFO - 'HOME': '/home/cltbld', 22:01:05 INFO - 'LANG': 'en_US.UTF-8', 22:01:05 INFO - 'LANGUAGE': 'en_US:en', 22:01:05 INFO - 'LOGNAME': 'cltbld', 22:01:05 INFO - 'MAIL': '/var/mail/cltbld', 22:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:05 INFO - 'MOZ_NO_REMOTE': '1', 22:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:05 INFO - 'NO_EM_RESTART': '1', 22:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:05 INFO - 'PWD': '/builds/slave/test', 22:01:05 INFO - 'SHELL': '/bin/bash', 22:01:05 INFO - 'SHLVL': '1', 22:01:05 INFO - 'TERM': 'linux', 22:01:05 INFO - 'TMOUT': '86400', 22:01:05 INFO - 'USER': 'cltbld', 22:01:05 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:05 INFO - '_': '/tools/buildbot/bin/python'} 22:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01:05 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 22:01:05 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 22:01:05 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 22:01:05 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 22:01:05 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 22:01:05 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 22:01:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:07 INFO - Installing collected packages: mozsystemmonitor 22:01:07 INFO - Running setup.py install for mozsystemmonitor 22:01:07 INFO - Successfully installed mozsystemmonitor 22:01:07 INFO - Cleaning up... 22:01:07 INFO - Return code: 0 22:01:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01:07 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')]} 22:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:07 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')]} 22:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:07 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:07 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 22:01:07 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 22:01:07 INFO - Using env: {'DISPLAY': ':0', 22:01:07 INFO - 'HOME': '/home/cltbld', 22:01:07 INFO - 'LANG': 'en_US.UTF-8', 22:01:07 INFO - 'LANGUAGE': 'en_US:en', 22:01:07 INFO - 'LOGNAME': 'cltbld', 22:01:07 INFO - 'MAIL': '/var/mail/cltbld', 22:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:07 INFO - 'MOZ_NO_REMOTE': '1', 22:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:07 INFO - 'NO_EM_RESTART': '1', 22:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:07 INFO - 'PWD': '/builds/slave/test', 22:01:07 INFO - 'SHELL': '/bin/bash', 22:01:07 INFO - 'SHLVL': '1', 22:01:07 INFO - 'TERM': 'linux', 22:01:07 INFO - 'TMOUT': '86400', 22:01:07 INFO - 'USER': 'cltbld', 22:01:07 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:07 INFO - '_': '/tools/buildbot/bin/python'} 22:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:08 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:08 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 22:01:08 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 22:01:08 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 22:01:08 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 22:01:08 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 22:01:08 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 22:01:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:09 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 22:01:09 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 22:01:09 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 22:01:09 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 22:01:09 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 22:01:09 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 22:01:10 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:10 INFO - warning: no files found matching '*.txt' 22:01:10 INFO - no previously-included directories found matching 'build' 22:01:10 INFO - no previously-included directories found matching 'dist' 22:01:10 INFO - no previously-included directories found matching '.git*' 22:01:10 INFO - Installing collected packages: jsonschema, functools32 22:01:10 INFO - Running setup.py install for functools32 22:01:10 INFO - warning: no files found matching '*.txt' 22:01:10 INFO - no previously-included directories found matching 'build' 22:01:10 INFO - no previously-included directories found matching 'dist' 22:01:10 INFO - no previously-included directories found matching '.git*' 22:01:10 INFO - Successfully installed jsonschema functools32 22:01:10 INFO - Cleaning up... 22:01:10 INFO - Return code: 0 22:01:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01:10 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')]} 22:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 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')]} 22:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:10 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:10 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 22:01:10 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 22:01:10 INFO - Using env: {'DISPLAY': ':0', 22:01:10 INFO - 'HOME': '/home/cltbld', 22:01:10 INFO - 'LANG': 'en_US.UTF-8', 22:01:10 INFO - 'LANGUAGE': 'en_US:en', 22:01:10 INFO - 'LOGNAME': 'cltbld', 22:01:10 INFO - 'MAIL': '/var/mail/cltbld', 22:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:10 INFO - 'MOZ_NO_REMOTE': '1', 22:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:10 INFO - 'NO_EM_RESTART': '1', 22:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:10 INFO - 'PWD': '/builds/slave/test', 22:01:10 INFO - 'SHELL': '/bin/bash', 22:01:10 INFO - 'SHLVL': '1', 22:01:10 INFO - 'TERM': 'linux', 22:01:10 INFO - 'TMOUT': '86400', 22:01:10 INFO - 'USER': 'cltbld', 22:01:10 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:10 INFO - '_': '/tools/buildbot/bin/python'} 22:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:10 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:10 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 22:01:10 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 22:01:10 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 22:01:10 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 22:01:10 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 22:01:10 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 22:01:12 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:12 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 22:01:12 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 22:01:12 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 22:01:12 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 22:01:12 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 22:01:12 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 22:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:13 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 22:01:13 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 22:01:13 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 22:01:13 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 22:01:13 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 22:01:13 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 22:01:13 INFO - Downloading docopt-0.6.1.tar.gz 22:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:13 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:13 INFO - Running setup.py install for blobuploader 22:01:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:13 INFO - Running setup.py install for requests 22:01:13 INFO - Running setup.py install for docopt 22:01:13 INFO - Successfully installed blobuploader requests docopt 22:01:13 INFO - Cleaning up... 22:01:13 INFO - Return code: 0 22:01:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:13 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')]} 22:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:13 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')]} 22:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:13 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:13 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 22:01:13 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 22:01:13 INFO - Using env: {'DISPLAY': ':0', 22:01:13 INFO - 'HOME': '/home/cltbld', 22:01:13 INFO - 'LANG': 'en_US.UTF-8', 22:01:13 INFO - 'LANGUAGE': 'en_US:en', 22:01:13 INFO - 'LOGNAME': 'cltbld', 22:01:13 INFO - 'MAIL': '/var/mail/cltbld', 22:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:13 INFO - 'MOZ_NO_REMOTE': '1', 22:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:13 INFO - 'NO_EM_RESTART': '1', 22:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:13 INFO - 'PWD': '/builds/slave/test', 22:01:13 INFO - 'SHELL': '/bin/bash', 22:01:13 INFO - 'SHLVL': '1', 22:01:13 INFO - 'TERM': 'linux', 22:01:13 INFO - 'TMOUT': '86400', 22:01:13 INFO - 'USER': 'cltbld', 22:01:13 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:13 INFO - '_': '/tools/buildbot/bin/python'} 22:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:14 INFO - Running setup.py (path:/tmp/pip-Kq8ca8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:14 INFO - Running setup.py (path:/tmp/pip-2Rjpmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:14 INFO - Running setup.py (path:/tmp/pip-Srnwn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:14 INFO - Running setup.py (path:/tmp/pip-O_p0uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:14 INFO - Running setup.py (path:/tmp/pip-C7FUAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:14 INFO - Running setup.py (path:/tmp/pip-onqyPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:14 INFO - Running setup.py (path:/tmp/pip-lR8fch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:14 INFO - Running setup.py (path:/tmp/pip-HwaZY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:14 INFO - Running setup.py (path:/tmp/pip-0fjbVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:14 INFO - Running setup.py (path:/tmp/pip-EBK9w0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:14 INFO - Running setup.py (path:/tmp/pip-Xfbilc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:14 INFO - Running setup.py (path:/tmp/pip-Yldljs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:15 INFO - Running setup.py (path:/tmp/pip-frF0XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:15 INFO - Running setup.py (path:/tmp/pip-Ew8W33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:15 INFO - Running setup.py (path:/tmp/pip-8wN2Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:15 INFO - Running setup.py (path:/tmp/pip-6tPTOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:15 INFO - Running setup.py (path:/tmp/pip-mBxti_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:15 INFO - Running setup.py install for manifestparser 22:01:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:15 INFO - Running setup.py install for mozcrash 22:01:15 INFO - Running setup.py install for mozdebug 22:01:15 INFO - Running setup.py install for mozdevice 22:01:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:15 INFO - Running setup.py install for mozfile 22:01:15 INFO - Running setup.py install for mozhttpd 22:01:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for mozinfo 22:01:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for mozInstall 22:01:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for mozleak 22:01:16 INFO - Running setup.py install for mozlog 22:01:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for moznetwork 22:01:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for mozprocess 22:01:16 INFO - Running setup.py install for mozprofile 22:01:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for mozrunner 22:01:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:16 INFO - Running setup.py install for mozscreenshot 22:01:16 INFO - Running setup.py install for moztest 22:01:17 INFO - Running setup.py install for mozversion 22:01:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:17 INFO - Cleaning up... 22:01:17 INFO - Return code: 0 22:01:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:17 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:17 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 22:01:17 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 22:01:17 INFO - Using env: {'DISPLAY': ':0', 22:01:17 INFO - 'HOME': '/home/cltbld', 22:01:17 INFO - 'LANG': 'en_US.UTF-8', 22:01:17 INFO - 'LANGUAGE': 'en_US:en', 22:01:17 INFO - 'LOGNAME': 'cltbld', 22:01:17 INFO - 'MAIL': '/var/mail/cltbld', 22:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:17 INFO - 'MOZ_NO_REMOTE': '1', 22:01:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:17 INFO - 'NO_EM_RESTART': '1', 22:01:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:17 INFO - 'PWD': '/builds/slave/test', 22:01:17 INFO - 'SHELL': '/bin/bash', 22:01:17 INFO - 'SHLVL': '1', 22:01:17 INFO - 'TERM': 'linux', 22:01:17 INFO - 'TMOUT': '86400', 22:01:17 INFO - 'USER': 'cltbld', 22:01:17 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:17 INFO - '_': '/tools/buildbot/bin/python'} 22:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:17 INFO - Running setup.py (path:/tmp/pip-NIrd0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:17 INFO - Running setup.py (path:/tmp/pip-2td9nF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:17 INFO - Running setup.py (path:/tmp/pip-kzg5ZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:17 INFO - Running setup.py (path:/tmp/pip-NRQqAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:17 INFO - Running setup.py (path:/tmp/pip-XB2jII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:17 INFO - Running setup.py (path:/tmp/pip-y40GU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:17 INFO - Running setup.py (path:/tmp/pip-Er9kfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:17 INFO - Running setup.py (path:/tmp/pip-MwovoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:17 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)) 22:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:17 INFO - Running setup.py (path:/tmp/pip-95euCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:18 INFO - Running setup.py (path:/tmp/pip-lT1UKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:18 INFO - Running setup.py (path:/tmp/pip-cV2RKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:18 INFO - Running setup.py (path:/tmp/pip-MZGOhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:18 INFO - Running setup.py (path:/tmp/pip-S1AoTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:18 INFO - Running setup.py (path:/tmp/pip-M3jB1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:18 INFO - Running setup.py (path:/tmp/pip-kwNV8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:18 INFO - Running setup.py (path:/tmp/pip-H4iKeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:18 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)) 22:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:18 INFO - Running setup.py (path:/tmp/pip-xksBiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:18 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)) 22:01:18 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)) 22:01:18 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)) 22:01:18 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)) 22:01:18 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)) 22:01:18 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)) 22:01:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:20 INFO - Downloading blessings-1.6.tar.gz 22:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:20 INFO - Installing collected packages: blessings 22:01:20 INFO - Running setup.py install for blessings 22:01:20 INFO - Successfully installed blessings 22:01:20 INFO - Cleaning up... 22:01:20 INFO - Return code: 0 22:01:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:20 INFO - Reading from file tmpfile_stdout 22:01:20 INFO - Current package versions: 22:01:21 INFO - argparse == 1.2.1 22:01:21 INFO - blessings == 1.6 22:01:21 INFO - blobuploader == 1.2.4 22:01:21 INFO - docopt == 0.6.1 22:01:21 INFO - functools32 == 3.2.3-2 22:01:21 INFO - jsonschema == 2.5.1 22:01:21 INFO - manifestparser == 1.1 22:01:21 INFO - mozInstall == 1.12 22:01:21 INFO - mozcrash == 0.17 22:01:21 INFO - mozdebug == 0.1 22:01:21 INFO - mozdevice == 0.48 22:01:21 INFO - mozfile == 1.2 22:01:21 INFO - mozhttpd == 0.7 22:01:21 INFO - mozinfo == 0.9 22:01:21 INFO - mozleak == 0.1 22:01:21 INFO - mozlog == 3.2 22:01:21 INFO - moznetwork == 0.27 22:01:21 INFO - mozprocess == 0.22 22:01:21 INFO - mozprofile == 0.28 22:01:21 INFO - mozrunner == 6.11 22:01:21 INFO - mozscreenshot == 0.1 22:01:21 INFO - mozsystemmonitor == 0.3 22:01:21 INFO - moztest == 0.7 22:01:21 INFO - mozversion == 1.4 22:01:21 INFO - psutil == 3.1.1 22:01:21 INFO - requests == 1.2.3 22:01:21 INFO - wsgiref == 0.1.2 22:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:21 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:21 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 22:01:21 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 22:01:21 INFO - Using env: {'DISPLAY': ':0', 22:01:21 INFO - 'HOME': '/home/cltbld', 22:01:21 INFO - 'LANG': 'en_US.UTF-8', 22:01:21 INFO - 'LANGUAGE': 'en_US:en', 22:01:21 INFO - 'LOGNAME': 'cltbld', 22:01:21 INFO - 'MAIL': '/var/mail/cltbld', 22:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:21 INFO - 'MOZ_NO_REMOTE': '1', 22:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:21 INFO - 'NO_EM_RESTART': '1', 22:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:21 INFO - 'PWD': '/builds/slave/test', 22:01:21 INFO - 'SHELL': '/bin/bash', 22:01:21 INFO - 'SHLVL': '1', 22:01:21 INFO - 'TERM': 'linux', 22:01:21 INFO - 'TMOUT': '86400', 22:01:21 INFO - 'USER': 'cltbld', 22:01:21 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:21 INFO - '_': '/tools/buildbot/bin/python'} 22:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 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)) 22:01:21 INFO - Cleaning up... 22:01:21 INFO - Return code: 0 22:01:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:21 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:21 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 22:01:21 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 22:01:21 INFO - Using env: {'DISPLAY': ':0', 22:01:21 INFO - 'HOME': '/home/cltbld', 22:01:21 INFO - 'LANG': 'en_US.UTF-8', 22:01:21 INFO - 'LANGUAGE': 'en_US:en', 22:01:21 INFO - 'LOGNAME': 'cltbld', 22:01:21 INFO - 'MAIL': '/var/mail/cltbld', 22:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:21 INFO - 'MOZ_NO_REMOTE': '1', 22:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:21 INFO - 'NO_EM_RESTART': '1', 22:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:21 INFO - 'PWD': '/builds/slave/test', 22:01:21 INFO - 'SHELL': '/bin/bash', 22:01:21 INFO - 'SHLVL': '1', 22:01:21 INFO - 'TERM': 'linux', 22:01:21 INFO - 'TMOUT': '86400', 22:01:21 INFO - 'USER': 'cltbld', 22:01:21 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:21 INFO - '_': '/tools/buildbot/bin/python'} 22:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:21 INFO - Cleaning up... 22:01:21 INFO - Return code: 0 22:01:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:21 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 0x7f6bc7924f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x232a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23f5350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, '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': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', '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 22:01:21 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 22:01:21 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 22:01:21 INFO - Using env: {'DISPLAY': ':0', 22:01:21 INFO - 'HOME': '/home/cltbld', 22:01:21 INFO - 'LANG': 'en_US.UTF-8', 22:01:21 INFO - 'LANGUAGE': 'en_US:en', 22:01:21 INFO - 'LOGNAME': 'cltbld', 22:01:21 INFO - 'MAIL': '/var/mail/cltbld', 22:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:21 INFO - 'MOZ_NO_REMOTE': '1', 22:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:21 INFO - 'NO_EM_RESTART': '1', 22:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:21 INFO - 'PWD': '/builds/slave/test', 22:01:21 INFO - 'SHELL': '/bin/bash', 22:01:21 INFO - 'SHLVL': '1', 22:01:21 INFO - 'TERM': 'linux', 22:01:21 INFO - 'TMOUT': '86400', 22:01:21 INFO - 'USER': 'cltbld', 22:01:21 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:21 INFO - '_': '/tools/buildbot/bin/python'} 22:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:21 INFO - Downloading/unpacking flake8 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:21 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 22:01:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:01:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:01:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:24 INFO - no previously-included directories found matching 'docs/_build' 22:01:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:01:24 INFO - Running setup.py install for pep8 22:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:01:24 INFO - no previously-included directories found matching 'docs/_build' 22:01:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:01:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:01:24 INFO - Cleaning up... 22:01:24 INFO - Return code: 0 22:01:24 INFO - Running post-action listener: _resource_record_post_action 22:01:24 INFO - Running post-action listener: _start_resource_monitoring 22:01:24 INFO - Starting resource monitoring. 22:01:24 INFO - ##### 22:01:24 INFO - ##### Finished create-virtualenv step (success) 22:01:24 INFO - ##### 22:01:24 INFO - ##### 22:01:24 INFO - ##### Running install step. 22:01:24 INFO - ##### 22:01:24 INFO - Running pre-action listener: _resource_record_pre_action 22:01:24 INFO - Running main action method: install 22:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:24 INFO - Reading from file tmpfile_stdout 22:01:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:24 INFO - Reading from file tmpfile_stdout 22:01:24 INFO - Output received: 22:01:24 INFO - Usage: mozinstall [options] installer 22:01:24 INFO - Options: 22:01:24 INFO - -h, --help show this help message and exit 22:01:24 INFO - -d DEST, --destination=DEST 22:01:24 INFO - Directory to install application into. [default: 22:01:24 INFO - "/builds/slave/test"] 22:01:24 INFO - --app=APP Application being installed. [default: firefox] 22:01:24 INFO - mkdir: /builds/slave/test/build/application 22:01:24 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'] 22:01:24 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 22:01:41 INFO - Reading from file tmpfile_stdout 22:01:41 INFO - Output received: 22:01:41 INFO - /builds/slave/test/build/application/firefox/firefox 22:01:41 INFO - Running post-action listener: _resource_record_post_action 22:01:41 INFO - ##### 22:01:41 INFO - ##### Finished install step (success) 22:01:41 INFO - ##### 22:01:41 INFO - ##### 22:01:41 INFO - ##### Running run-tests step. 22:01:41 INFO - ##### 22:01:41 INFO - Running pre-action listener: _resource_record_pre_action 22:01:41 INFO - Running main action method: run_tests 22:01:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:01:41 INFO - Python 2.7.3 22:01:41 INFO - Return code: 0 22:01:41 INFO - grabbing minidump binary from tooltool 22:01:41 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')]} 22:01:41 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 0x24841c0>, '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 0x24850c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2485550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:01:41 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 22:01:41 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 22:01:41 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 22:01:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:01:41 INFO - Return code: 0 22:01:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:01:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:01:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:01:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:01:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:01:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:01:42 INFO - Return code: 0 22:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:01:42 INFO - Using env: {'DISPLAY': ':0', 22:01:42 INFO - 'HOME': '/home/cltbld', 22:01:42 INFO - 'LANG': 'en_US.UTF-8', 22:01:42 INFO - 'LANGUAGE': 'en_US:en', 22:01:42 INFO - 'LOGNAME': 'cltbld', 22:01:42 INFO - 'MAIL': '/var/mail/cltbld', 22:01:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:01:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:42 INFO - 'MOZ_NO_REMOTE': '1', 22:01:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:01:42 INFO - 'NO_EM_RESTART': '1', 22:01:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:42 INFO - 'PWD': '/builds/slave/test', 22:01:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:01:42 INFO - 'SHELL': '/bin/bash', 22:01:42 INFO - 'SHLVL': '1', 22:01:42 INFO - 'TERM': 'linux', 22:01:42 INFO - 'TMOUT': '86400', 22:01:42 INFO - 'USER': 'cltbld', 22:01:42 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192', 22:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:42 INFO - '_': '/tools/buildbot/bin/python'} 22:01:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:01:42 INFO - mozversion application_buildid: 20160602193138 22:01:42 INFO - mozversion application_changeset: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 22:01:42 INFO - mozversion application_display_name: Nightly 22:01:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:01:42 INFO - mozversion application_name: Firefox 22:01:42 INFO - mozversion application_remotingname: firefox 22:01:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:42 INFO - mozversion application_vendor: Mozilla 22:01:42 INFO - mozversion application_version: 49.0a1 22:01:42 INFO - mozversion platform_buildid: 20160602193138 22:01:42 INFO - mozversion platform_changeset: c5c9bc65a408fa0c26ec742c0950b6fc484cb635 22:01:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:01:42 INFO - mozversion platform_version: 49.0a1 22:01:42 INFO - using testdate: 1464930102 22:01:42 INFO - actual date: 1464930102 22:01:42 INFO - starting webserver on 'localhost:60388' 22:01:42 INFO - SUITE-START | Running 2 tests 22:01:42 INFO - TEST-START | dromaeo_css 22:01:42 INFO - operating with platform_type : linux_ 22:01:42 INFO - Initialising browser for dromaeo_css test... 22:01:42 INFO - TEST-INFO | started process 18734 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5FeHXR/profile http://localhost:60388/getInfo.html) 22:01:47 INFO - PROCESS | 18734 | __metrics Screen width/height:1600/1200 22:01:47 INFO - PROCESS | 18734 | colorDepth:24 22:01:47 INFO - PROCESS | 18734 | Browser inner width/height: 1024/697 22:01:47 INFO - PROCESS | 18734 | __metrics 22:01:48 ERROR - PROCESS | 18734 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:01:48 INFO - TEST-INFO | 18734: exit 0 22:01:48 INFO - Browser initialized. 22:01:48 INFO - Running cycle 1/1 for dromaeo_css test... 22:01:48 INFO - TEST-INFO | started process 18813 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5FeHXR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:00 INFO - PROCESS | 18813 | RSS: Main: 156639232 22:02:00 INFO - PROCESS | 18813 | 22:02:52 INFO - PROCESS | 18813 | 1464930172086 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] 22:05:00 INFO - PROCESS | 18813 | Cycle 1(1): loaded http://localhost:60388/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60388/tests/dromaeo/cssquery-ext.html) 22:05:01 INFO - PROCESS | 18813 | RSS: Main: 259104768 22:05:01 INFO - PROCESS | 18813 | 22:08:03 INFO - PROCESS | 18813 | Cycle 1(1): loaded http://localhost:60388/tests/dromaeo/cssquery-ext.html (next: http://localhost:60388/tests/dromaeo/cssquery-jquery.html) 22:08:04 INFO - PROCESS | 18813 | RSS: Main: 243290112 22:08:04 INFO - PROCESS | 18813 | 22:11:06 INFO - PROCESS | 18813 | Cycle 1(1): loaded http://localhost:60388/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60388/tests/dromaeo/cssquery-mootools.html) 22:11:07 INFO - PROCESS | 18813 | RSS: Main: 247783424 22:11:07 INFO - PROCESS | 18813 | 22:14:09 INFO - PROCESS | 18813 | Cycle 1(1): loaded http://localhost:60388/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60388/tests/dromaeo/cssquery-prototype.html) 22:14:09 INFO - PROCESS | 18813 | RSS: Main: 240373760 22:14:09 INFO - PROCESS | 18813 | 22:17:12 INFO - PROCESS | 18813 | Cycle 1(1): loaded http://localhost:60388/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60388/tests/dromaeo/cssquery-yui.html) 22:17:12 INFO - PROCESS | 18813 | RSS: Main: 221024256 22:17:12 INFO - PROCESS | 18813 | 22:20:15 INFO - PROCESS | 18813 | Cycle 1(1): loaded http://localhost:60388/tests/dromaeo/cssquery-yui.html (next: http://localhost:60388/tests/dromaeo/cssquery-dojo.html) 22:20:16 INFO - PROCESS | 18813 | RSS: Main: 275513344 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | __start_tp_report 22:20:16 INFO - PROCESS | 18813 | _x_x_mozilla_page_load 22:20:16 INFO - PROCESS | 18813 | _x_x_mozilla_page_load_details 22:20:16 INFO - PROCESS | 18813 | |i|pagename|runs| 22:20:16 INFO - PROCESS | 18813 | |0;dojo.html;3172.6510083890767;3184.3949649566584;3191.29791445882;3217.372612340592;3257.2182676157713;5317.601179911512;5353.170258609922;5427.674339539631;5560.805371811987;5581.302337207854;5466.726663666821;5472.890542189154;5495.807646732365;5514.503694667479;5565.6104072714925;4875.341828853104;4895.55939965403;4977.377827771528;4980.327655766479;5155.639105262633;5039.521245481674;5064.594832413398;5085;5111.05445492585;5111.769970351324;4834.540718631724;4919.950800492003;4930.087933732271;4943.233798761198;4987.351644286256;4939.135651261015;4946.629002824802;5021.874453138701;5025.547700706954;5067.0879241736475;4811.8075276988875;4961.677490963076;4985.775640096168;5002.1746411842005;5037.773300201499;5333.439988801198;5384.91922621161;5392.325959255093;5423.620346575742;5461.808836690737;5902.468777809975;5922.466977972003;5931.317898441648;5933.703314834241;5936.406359364072;5709.514691251248;5811.941880581182;5865.296164460249;5975.551833612496;6022.217111775485;5536.667799931976;5553.972230138864;5565.30433695788;5568.387477377485;5580.051391263495;6034.948568595861;6051.8946986238425;6056.547619047619;6107.96946015276;6112.235970503687;5608.523275521545;5730.226419433323;5742.885142297131;5773.509251713567;5833.125031245262;5886.558508111909;5892.086726557391;5941.465268125889;5946.791862284799;5946.940530594725;5811.622244554091;5883.794067207627;5883.941160588425;5886.499647529922;5894.557908156819;5644.435556444323;5745.597808153389;5756.280464941882;5788.305403351625;5865.618734782225;4424.977875110604;4459.44256967879;4469.9553004469535;4477.462704475483;4513.729176249435;3948.8420463181724;3950.0914789598555;3987.5414327352146;4018.3570628699267;4039.2325458162854;5403.054465468527;5475.069238229431;5510.0632892408385;5533.004059269289;5534.225208470817;5960.493358064527;5961.970190149022;5965.224520812267;5974.283086029617;6002.819915402458;5384.326959130109;5550.722463876871;5562.082256427725;5562.803997140634;5570.805021824217;4966.850994470172;5057.8735531612;5083.87290317745;5104.336436263262;5153;6097.298810636834;6108.816735497942;6110.114033465123;6116.816495505142;6130;5696.373398926197;5932.970335148297;5934.703264836828;5942.227510423617;5944.256967879015;5980.551458640706;6029.1559181715475;6035.939640603538;6085.786997455068;6098.969505152446;5750.942490575041;5773.249477568057;5773.278340207474;5777.335606405317;5794.333651630117;5764.538836892954;5822.388649191774;5829.183914251923;5865.677387743715;5878.794242201502;5680.573956953162;5744.080947048619;5761.308642962704;5766.135079738073;5780.075187969905;5754.798582049608;5760.769569217185;5760.798372056958;5765.798197063083;5768.567357448124;5515.669059856422;5573;5628.015097358028;5668.0364338063455;5679.9148012779015;5806.158107074535;5842.065269556851;5847.502962248256;5848.473637372657;5856;5638.182463542681;5645.943540564542;5648.858778530405;5649.491545760901;5649.915251271316 22:20:16 INFO - PROCESS | 18813 | |1;ext.html;588.4733163818382;613.2058983616216;620.9999999999999;636.1856823266221;637.8501052252722;34641.48037779435;37144.442833357454;37273.99999999996;37287;44528.55471445285;42154.7892260539;46206.07587848241;46331.768341158335;46429.30356044662;46440.76779616105;8673.089325620798;8842.778930526756;8844.911550884473;8848.2478989286;8853;8625.137486251388;8821.647134114659;8826.691065812698;8826.823463530725;8834.116588341147;8800.383973121883;8822.602982865754;8841.16008979146;8849.867251991192;8867.822643547126;8774.561271936409;8816.911830881672;8825.867611985857;8827.16141966512;8836.381453298272;8730.257928076122;8828.8675669865;8832.646694132227;8835.072317406644;8837.513936733478;42654.78672606617;43165;43210.351844722354;43330;43407.13185736299;3519.6480351964597;3591.6408359164134;3671.724620653462;3884.1843212925037;4075.816588253506;32264.838675806706;32277.51583726246;32660.836695816368;32678.509822352684;34359;3795.8671446499516;4031.113155105872;4281.186574550826;4332.415123958257;4427.180971520279;324886;325647;327526;327951;328952.9999999976;60808.39191608116;61121;61239.6938015316;61310;61344.69327653333;65114;65143.34856651467;65175.674121630036;65242.347576524575;65262.673686631264;16908.239129239213;17144.828551714323;17175.484735457958;17183.742243866353;17187.226574804165;11962.461689224006;11967.162298639012;11968.10239232061;11979.34113623759;11996.100292478099;17078.4033884259;18513.7222941656;18566.443006709822;18657.53356166107;18677.346292879683;6333.429991300713;6610.0745895574655;6632.137822083195;6721.26066132725;6723.428508576728;839.8320335932838;970.8932017478072;1066.2376400873413;1124.2804605052772;1192.66605350502;31478.623128680934;32670.346593068007;32811.17972050718;33110.66889331124;33293.667063329056;1088.2708585247904;1145.2212495503138;1174.8296497008057;1205.7166565857017;1257.6792917805901;17120.74318885218;17337;17359.392421265195;17459.738103928452;17510.562235944202;479775;481376;490267;492825;492893;58975;59155;59237.40762592319;59404.10893836596;59441.70279148577;1127.6673381352357;1234.2224398628807;1348.426918559628;1358.2801115409047;1391.8816900563363;29912.25219369533;33969;34328.14179645429;34539.63074964193;34657;53587.73206133944;53866;54132;54285;54416;478104;491630;492283;493713;493860;35900.82049589735;48322.27516587183;48504.55200987269;49856.750716246184;49956;50118.498815011386;50262.246066309766;50711.74644126903;50805.237921431944;51066;40294.39558406685;42108;42138.15723685448;43106.78446607747;43234.35148472712;40910;40914.38628420635;41405;41625.167496649294;41715.16569668529;41280.1743965113;41528.1694366117;41793.79103104465;41985.370219447344;42296;36583.81708091549;38408.807955960045;38891.80554097211;39004.219915602094;39259.41110883282;28817.85591072031;28976.13071607855;29279;29343.85328073346;29550.704492954796 22:20:16 INFO - PROCESS | 18813 | |2;jquery.html;383.8464614154338;387.41306920016183;389.6869352583867;397.4257198348386;409.77052850403777;14069.610255851618;16094.195290235471;18817.62364752706;19520.90239548802;19548.218071277166;13744.58766237012;15282.69434611307;15305.540833774976;15529.611759706013;15768;3913.4129880517867;4059.776712280823;4064.1465292288653;4064.715469917107;5701.57238207134;5519.862003449905;5615.5507559395355;5638.295213098363;5648.378678345379;5654.717264136797;4116.361963895601;5570.832875013756;5598.328200615912;5612.270404847364;5624.775008999655;5946.881062378749;6212.068189771525;6212.937870621304;6320.494360451153;6331.525135614801;5341.118715411992;5488.121900495901;5528.170774383834;5548.889022219573;5556.777728890839;21781.455463613376;22580.774192258195;22603.434914127112;22803.31590052301;22939.88530057356;2309.145616122046;2520.659710939018;2531.329197762576;2616.3459135216194;2849.216465471991;13381.46474141043;13555.25446100464;13681.589552313348;14018.228997405138;14231.715365692731;1876.9436916892514;1919.1008325071484;1928;1954.7556555430572;1966.0366420453875;75708;76171.23828761697;76352.61823690846;77664.61167694181;78686.60656696737;9191.954040229755;9816.705498834903;10209.642662506925;10225.795484090426;10268.948655256676;14005.929970350287;14083.859161408254;14102.224377659328;14127.78808317876;14133.85866141346;8040.557769322626;8071.717489887943;8126.9593652032545;8151;8266.338692904554;6604.966975165094;7612.7716168515035;7826.099998500132;7989.041315042232;8124;6316.873662526724;6418.24015673265;6464.288928217892;6470.12653291809;6544.149260596188;1767.1341042889148;1793.1213705284317;1819.8817076890225;1878.8402985746366;1888.0937150167997;898.3621628643737;1029.1920842139034;1029.5727273181697;1072.5924148823397;1106.9889301106887;18160.55066508714;18810.24759009652;18975.71536426955;19185.8081419184;19194;1015.7968406318765;1022.0903395977585;1046.9214808889365;1075.537518866879;1083.5124194112682;10647.254692171473;11076.335419874833;11097.38964356968;11110.61112861046;11168.553257869595;437303;437673;442278;446822;454558;79397;80074.59962700149;80598.19401805908;81668;82983;1038.870141232346;1056.3503445381189;1106.9391183484986;1123.3203911633507;1127.1940562497853;16862.747058793884;16946.491605251864;17161.141942903054;17253.65492690114;17383.82616173822;37515.43726844045;37818.62181378151;38125.4281185788;38732.61267387403;39465.210695786496;49961;52699.419017429536;52931.735341323045;53096.20355694745;53167.20249196343;8393.95803020981;8396.286315663237;8506.489610623383;8549.27331176832;8660.17728315809;12632.368381581067;12645.494180232688;13135.211887286794;13160.670983225113;13245.867541324464;11176.720581985515;11328.32030078198;11388.430578470878;11840.407979601157;11864.82202766942;10996.505157268102;11221.775564488828;11278.436078195893;11506.137039722222;11656.941715291709;11766.235194712317;11790.351530665896;11996.040316774812;12008.39958002114;12062.095342849498;8260;8409.075001749925;8438.451500652438;8604.870926936226;8614.698485552975;11194.384308863091;11388.544458221579;11842.407879605813;11892.702682433008;11935.104867134825 22:20:16 INFO - PROCESS | 18813 | |3;mootools.html;1327.150623600895;1478.1722235548093;1511.7959075524805;1670.1816110106051;1774.0508827777142;8277.627506762195;8622.310215182786;8631.956840215798;8857.202851743341;8872.600732967016;8599.054104048566;8768.386212965095;8795.076516965722;8803.295736341093;8816.647334106628;1972.8816271023713;2211.016097836455;2262.6266666000092;2273.818094552432;2292.1404473322505;2334.3697201755517;2338.8479748816358;2339.7075365579303;2343.4024323797407;2346.038124369009;2298.092253559843;2315.513742114158;2323.1288266899915;2325.430269583954;2327.5228578141523;1938.6122775444967;2149.6990421341025;2150.4193867655804;2223.499712564676;2225.3324002799095;2222.922197723088;2242.338510139513;2275.977240227593;2279.9968014073756;2282.3609389371004;8730.257928076122;8805.427647202976;8824.426412283181;8832.735017949408;8836.028036915934;5077.898442031139;5202.947970520322;5206.297149884793;5207.583393328525;5213.165893457067;2935.3248752786767;2990.8055976361684;3067.2331917020747;3086.1975886269734;3142.7800053996;1035.746242170673;1038.2005855491304;1050.385524468187;1051.2168434516334;1061.8460323253084;12119.454624541828;12141.210821296587;12152.878471215352;12157.392130393533;12174.452149653198;4432.468103827497;4434.024514606781;4551.658625603093;4558.521355257651;4568.428946381702;10337.534810933526;10342.120919721758;10355.223358248162;10370;10376.533056012497;5113.8721531961255;5176.9741151294;5178.663386879841;5188.351456067991;5190.429052804188;4170.082581831992;4662.277347011219;4780.760961951958;4801.063792560487;4837.975810120997;3942.211557688474;3987.4616926715103;4043.7775922323967;4096.40602112692;4104.281750693676;1243.055277988717;1270.510853321478;1272.8027155790867;1275.4196840437578;1293.5149319005372;873.8208667223204;960.7886265021684;981.9656312029044;986.2455221755363;1000.7798284377425;5962.22491076166;5977.940220597825;6095.939040609625;6204.410580994889;6277.372262773686;429.6305177547307;430.18265295937346;440.583648452216;440.9074094440139;444.65317052698947;4201.306784380543;4335.934960975588;4377.343398490252;4381.3427985801745;4414.713043652152;10002.49987500622;10235.334703244265;10312.071913527818;10322.587096516056;10333.534990925426;10056.094951454405;10115.39307641544;10204.285700000928;10576.629817956335;10586.841197382046;7910.8813367798375;7952.920470795218;7984.840303193903;8010.239027292398;8040;5939.821805345847;6047.969760151171;6049.637021778707;6144.201253837151;6195.628262304276;9279.489628070522;9413.246940244628;9581.568829402555;9634.855477167708;9644.855327169958;9635.662751803653;9659.275554333593;9699.321047526888;9795.06946840049;9803.656872009446;7510.098788145458;7531.3598344141365;7610.847783044419;7641.961790191236;7646.9617651911385;8184.54984975832;8196.385271104571;8260.586970651564;8278.379121565786;8322;6940.687669055005;7250.709971601077;7437.107547094383;7455.589942553212;7531.322181003736;7170.139583250043;7227.421806255592;7361.374283185989;7456.6271686414375;7501.912222727565;6766.8646627068165;6808.65956702153;6839.110915580943;6839.350261725128;6843.692033858389;4962.156433406401;5081.568066714371;5184.559312458483;5204.427512973645;5206.062908676475;5281.128613778684;5360.655901415086;5423.864403389947;5459.918101228405;5487.917681234864 22:20:16 INFO - PROCESS | 18813 | |4;prototype.html;321.1057205682176;328.5777775558408;332.90345799718085;337.5965720963447;337.972962163027;5252.896891652753;5347.304850369452;5352.678839269646;5367.2485851980655;5401.78392864286;5217.660852044614;5269.62989622698;5278.736063196834;5283.867903302419;5298.761555729991;3944.4872166618297;3950.407438884176;4037.5962403759536;4100.36444351126;4112.691548133887;4048.360414032322;4080.3675430308203;4092.3656833190753;4100.261952848501;4104.630583247507;3877.4765406670163;4046.312126938413;4058.269511487931;4063.6545893599077;4065.512138543379;3806.9252257519443;3960.8019599020076;3975.2049590081956;4097.242010228118;4125.864343099266;3624.691901188402;3631.4189729643394;3638.2905333459985;3644.7995360255177;3650.689691376236;5150.175971844487;5153.768080436389;5160.6129540284255;5161.948380516184;5171.948280517222;4529.184746745585;4695.389599352096;4696.365990591295;4703.529647035303;4714;5449.8827740313145;5468.589855760794;5485.573750824775;5491.203775452577;5520.641158324696;3790.431435284702;3912.68698504119;3947.703922205846;3962.702797290186;3963.7027222958395;5673.489385955242;5717.684932465556;5748.770049198027;5787.652740835563;5792.9420705793245;4010.2981978153707;4010.5588385277597;4011.498562679665;4019.658329042006;4020.7587544746825;3831.9616803832164;4065.59344065597;4075.8165882534763;4080.2655522006326;4115.662409716854;4536.183486972311;4590.196715574761;4593.540645935446;4613.192691279013;4626.1441633297945;5496.048902639585;5503.844192719494;5542.891421715673;5543.61194716366;5576.832695019156;1354.7290541891662;1391.4295138993166;1409.5348534983636;1410.978835317471;1416.5750274917484;874.790050387915;886.2909672262165;898.925783688499;900.3607438718452;906.1889608800219;5226.92159617606;5292.285541451932;5294.602904782157;5309.070912590281;5320.441353657888;5651.2935883014625;5661.49970257882;5669.461401166883;5696.515796157373;5702.800401985911;5074.644774865724;5289.603279754034;5320.791871373414;5344.732763361894;5386.680263335429;2985.7312841844337;3019.9849000755294;3035.5598438226334;3042.2546476113494;3057.685195366014;5824.854378640568;5826.359100498942;5845.210896528999;5855.795047173328;5867.442592953748;5592.979790912861;5813.680247586423;5814.3313518945015;5829.94170058294;5833.649981001154;5728.742206600713;5745.511631511285;5758.625689330264;5760.567957403212;5797.449242321973;5442.918356224739;5622.381538030895;5643.294588176478;5645.971770141123;5687.43125687428;5613.915791263053;5635.8591035224445;5644.629255712529;5660.37735849048;5674.687892165971;5815;5824.737886795019;5844.26946631671;5861.501772349398;5862.618929769551;3596.856125755045;3633.964320168739;3643.289558536166;3677.9816100919325;3699.9260014800093;5024.899502010013;5081.5699402902155;5102.336696229615;5116;5132.948670513397;4950.45544990058;4981.726005069611;4991.9001619966675;5002.724850133278;5004.874878128076;4953.479884612064;4999.425525643995;5113.923291150561;5582.7766889323975;5591.524720398812;5235.63873392913;5457.672539647634;5460.771326451517;5518.641288316247;5520.530754885719;5388.949154914754;5428.212909128233;5448.972755136199;5453.972730136483;5471.261379713687;5466.426025267289;5484.91772623403;5507.394186639386;5511.917321240265;5516.889662206813 22:20:16 INFO - PROCESS | 18813 | |5;yui.html;760.3043215457857;776.9533827970325;790.2966359939653;799.4994438264736;835.5822088955522;14126.576202713923;14400.423983040679;14898.106113633175;15413;15422.151781652005;14701.705965880676;14794.778078328834;14906.478273260438;14928;14935.925320373382;674.5413119079025;798.6406117247233;811.6591031766643;824.085265355458;825.4387016828554;811.8741595052746;822.8189798244376;822.9094799572042;824.221111050058;825.3685930263354;865.1219012702112;870.5908223135116;871.2898987325341;871.2942516561574;872.2672954718035;706.4101475268131;833.4665813879122;856.7900864288227;866.2766589897411;866.3935245328264;626.4706323156925;626.7117126121989;627.2003195925187;627.4697880291146;627.7645882793952;12484.687882803002;12487.188332758433;12509;12538.310392928386;12542.1847579907;13861.722765544733;14250;14350.425982960669;14513.637159071002;14711.485098021518;12544.651086322878;12708.173968692005;12729.427175777113;12755.362231888368;12772.997686733182;4329.112531930978;4403.735775853427;4419.9337009944875;4439.156560253541;4515.254982927813;71136.64431677808;71273.64363178203;72525.6373718128;72721.63639181823;76466.99999999945;10866.728331791768;11133.832992505118;11222.607208747819;11256.606018789467;11456.369899655432;20623.69064464034;20691.06890189945;20692.275770347966;20715.585688286148;20745.481362965744;8007.359411247086;8243.670253189925;8381.161883811692;8396.60073581033;8526.57367131641;4744.667873248908;4766.785494652749;4847.781849816767;4920.532549407801;4939.827106051271;3513.0865974846724;3617.04148400674;3617.439296908931;3619.2218672985437;3621.8732344367822;592.4016743089425;596.483728420956;602.9698515074228;604.3895665377912;604.7973928733924;567.7501899164355;680.8059702984625;690.0925283252507;722.2055738687402;737.6274981134493;9147.81704365909;9209.355345125778;9209.605502569246;9392.8591071134;9501.714948551555;674.4546594645849;732.3043109046428;742.406075139886;788.6766425765409;793.131520984521;4264.168487145039;4646.256598944153;4719.646026548023;4733.715977041388;4825.686330388514;70538.64730676313;74278;109243;117314;117813;97914;98630;99349.50325248472;99519;99729;70284.29715702879;70740;72716.63641681758;89681.55159224162;90253;9140.908590914272;9611.903880961381;9689.962902817279;9707;9806.901930980599;37060.81469592635;37657.4351384724;52265.750949573274;52359.47640523699;52544.7372763149;62878.371216287254;64509.35490645221;64778;67786;72977.6351118241;12182.878171218174;12308.722216948949;12440.19138755978;13226.4048117833;13776.517821876587;13741.312934353442;13917.373718182504;14074.648133796736;14263.572092837232;14609.904257180542;12822.763222196943;13159.276239806519;13250.072494925264;13262.734745304846;13290.335483225606;13006.674833128869;13082.476700932237;13095.607131786062;13550.864491355354;13567.118137321042;12495.250284982567;12907.354632268161;12977.41601627948;13166.078374513692;13207.273599952096;10038.447885366375;10191.847122293024;10233.846492302473;10434.113100386552;10573.365598064142;10788.514516846604;11067.03315751665;11218.66344009681;11230;11298.60454884075 22:20:16 INFO - PROCESS | 18813 | __end_tp_report 22:20:16 INFO - PROCESS | 18813 | __start_cc_report 22:20:16 INFO - PROCESS | 18813 | _x_x_mozilla_cycle_collect,862 22:20:16 INFO - PROCESS | 18813 | __end_cc_report 22:20:16 INFO - PROCESS | 18813 | __startTimestamp1464931216193__endTimestamp 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | ------- Summary: start ------- 22:20:16 INFO - PROCESS | 18813 | Number of tests: 6 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | [#0] dojo.html Cycles:165 Average:5454.84 Median:5648.86 stddev:624.00 (11.0%) stddev-sans-first:599.68 22:20:16 INFO - PROCESS | 18813 | Values: 3172.7 3184.4 3191.3 3217.4 3257.2 5317.6 5353.2 5427.7 5560.8 5581.3 5466.7 5472.9 5495.8 5514.5 5565.6 4875.3 4895.6 4977.4 4980.3 5155.6 5039.5 5064.6 5085.0 5111.1 5111.8 4834.5 4920.0 4930.1 4943.2 4987.4 4939.1 4946.6 5021.9 5025.5 5067.1 4811.8 4961.7 4985.8 5002.2 5037.8 5333.4 5384.9 5392.3 5423.6 5461.8 5902.5 5922.5 5931.3 5933.7 5936.4 5709.5 5811.9 5865.3 5975.6 6022.2 5536.7 5554.0 5565.3 5568.4 5580.1 6034.9 6051.9 6056.5 6108.0 6112.2 5608.5 5730.2 5742.9 5773.5 5833.1 5886.6 5892.1 5941.5 5946.8 5946.9 5811.6 5883.8 5883.9 5886.5 5894.6 5644.4 5745.6 5756.3 5788.3 5865.6 4425.0 4459.4 4470.0 4477.5 4513.7 3948.8 3950.1 3987.5 4018.4 4039.2 5403.1 5475.1 5510.1 5533.0 5534.2 5960.5 5962.0 5965.2 5974.3 6002.8 5384.3 5550.7 5562.1 5562.8 5570.8 4966.9 5057.9 5083.9 5104.3 5153.0 6097.3 6108.8 6110.1 6116.8 6130.0 5696.4 5933.0 5934.7 5942.2 5944.3 5980.6 6029.2 6035.9 6085.8 6099.0 5750.9 5773.2 5773.3 5777.3 5794.3 5764.5 5822.4 5829.2 5865.7 5878.8 5680.6 5744.1 5761.3 5766.1 5780.1 5754.8 5760.8 5760.8 5765.8 5768.6 5515.7 5573.0 5628.0 5668.0 5679.9 5806.2 5842.1 5847.5 5848.5 5856.0 5638.2 5645.9 5648.9 5649.5 5649.9 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | [#1] ext.html Cycles:180 Average:60758.07 Median:23693.99 stddev:116831.78 (493.1%) stddev-sans-first:117072.18 22:20:16 INFO - PROCESS | 18813 | Values: 588.5 613.2 621.0 636.2 637.9 34641.5 37144.4 37274.0 37287.0 44528.6 42154.8 46206.1 46331.8 46429.3 46440.8 8673.1 8842.8 8844.9 8848.2 8853.0 8625.1 8821.6 8826.7 8826.8 8834.1 8800.4 8822.6 8841.2 8849.9 8867.8 8774.6 8816.9 8825.9 8827.2 8836.4 8730.3 8828.9 8832.6 8835.1 8837.5 42654.8 43165.0 43210.4 43330.0 43407.1 3519.6 3591.6 3671.7 3884.2 4075.8 32264.8 32277.5 32660.8 32678.5 34359.0 3795.9 4031.1 4281.2 4332.4 4427.2 324886.0 325647.0 327526.0 327951.0 328953.0 60808.4 61121.0 61239.7 61310.0 61344.7 65114.0 65143.3 65175.7 65242.3 65262.7 16908.2 17144.8 17175.5 17183.7 17187.2 11962.5 11967.2 11968.1 11979.3 11996.1 17078.4 18513.7 18566.4 18657.5 18677.3 6333.4 6610.1 6632.1 6721.3 6723.4 839.8 970.9 1066.2 1124.3 1192.7 31478.6 32670.3 32811.2 33110.7 33293.7 1088.3 1145.2 1174.8 1205.7 1257.7 17120.7 17337.0 17359.4 17459.7 17510.6 479775.0 481376.0 490267.0 492825.0 492893.0 58975.0 59155.0 59237.4 59404.1 59441.7 1127.7 1234.2 1348.4 1358.3 1391.9 29912.3 33969.0 34328.1 34539.6 34657.0 53587.7 53866.0 54132.0 54285.0 54416.0 478104.0 491630.0 492283.0 493713.0 493860.0 35900.8 48322.3 48504.6 49856.8 49956.0 50118.5 50262.2 50711.7 50805.2 51066.0 40294.4 42108.0 42138.2 43106.8 43234.4 40910.0 40914.4 41405.0 41625.2 41715.2 41280.2 41528.2 41793.8 41985.4 42296.0 36583.8 38408.8 38891.8 39004.2 39259.4 28817.9 28976.1 29279.0 29343.9 29550.7 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | [#2] jquery.html Cycles:180 Average:26883.53 Median:22871.60 stddev:73060.62 (319.4%) stddev-sans-first:73238.48 22:20:16 INFO - PROCESS | 18813 | Values: 383.8 387.4 389.7 397.4 409.8 14069.6 16094.2 18817.6 19520.9 19548.2 13744.6 15282.7 15305.5 15529.6 15768.0 3913.4 4059.8 4064.1 4064.7 5701.6 5519.9 5615.6 5638.3 5648.4 5654.7 4116.4 5570.8 5598.3 5612.3 5624.8 5946.9 6212.1 6212.9 6320.5 6331.5 5341.1 5488.1 5528.2 5548.9 5556.8 21781.5 22580.8 22603.4 22803.3 22939.9 2309.1 2520.7 2531.3 2616.3 2849.2 13381.5 13555.3 13681.6 14018.2 14231.7 1876.9 1919.1 1928.0 1954.8 1966.0 75708.0 76171.2 76352.6 77664.6 78686.6 9192.0 9816.7 10209.6 10225.8 10268.9 14005.9 14083.9 14102.2 14127.8 14133.9 8040.6 8071.7 8127.0 8151.0 8266.3 6605.0 7612.8 7826.1 7989.0 8124.0 6316.9 6418.2 6464.3 6470.1 6544.1 1767.1 1793.1 1819.9 1878.8 1888.1 898.4 1029.2 1029.6 1072.6 1107.0 18160.6 18810.2 18975.7 19185.8 19194.0 1015.8 1022.1 1046.9 1075.5 1083.5 10647.3 11076.3 11097.4 11110.6 11168.6 437303.0 437673.0 442278.0 446822.0 454558.0 79397.0 80074.6 80598.2 81668.0 82983.0 1038.9 1056.4 1106.9 1123.3 1127.2 16862.7 16946.5 17161.1 17253.7 17383.8 37515.4 37818.6 38125.4 38732.6 39465.2 49961.0 52699.4 52931.7 53096.2 53167.2 8394.0 8396.3 8506.5 8549.3 8660.2 12632.4 12645.5 13135.2 13160.7 13245.9 11176.7 11328.3 11388.4 11840.4 11864.8 10996.5 11221.8 11278.4 11506.1 11656.9 11766.2 11790.4 11996.0 12008.4 12062.1 8260.0 8409.1 8438.5 8604.9 8614.7 11194.4 11388.5 11842.4 11892.7 11935.1 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | [#3] mootools.html Cycles:180 Average:5665.84 Median:4900.07 stddev:3211.60 (65.5%) stddev-sans-first:3204.06 22:20:16 INFO - PROCESS | 18813 | Values: 1327.2 1478.2 1511.8 1670.2 1774.1 8277.6 8622.3 8632.0 8857.2 8872.6 8599.1 8768.4 8795.1 8803.3 8816.6 1972.9 2211.0 2262.6 2273.8 2292.1 2334.4 2338.8 2339.7 2343.4 2346.0 2298.1 2315.5 2323.1 2325.4 2327.5 1938.6 2149.7 2150.4 2223.5 2225.3 2222.9 2242.3 2276.0 2280.0 2282.4 8730.3 8805.4 8824.4 8832.7 8836.0 5077.9 5202.9 5206.3 5207.6 5213.2 2935.3 2990.8 3067.2 3086.2 3142.8 1035.7 1038.2 1050.4 1051.2 1061.8 12119.5 12141.2 12152.9 12157.4 12174.5 4432.5 4434.0 4551.7 4558.5 4568.4 10337.5 10342.1 10355.2 10370.0 10376.5 5113.9 5177.0 5178.7 5188.4 5190.4 4170.1 4662.3 4780.8 4801.1 4838.0 3942.2 3987.5 4043.8 4096.4 4104.3 1243.1 1270.5 1272.8 1275.4 1293.5 873.8 960.8 982.0 986.2 1000.8 5962.2 5977.9 6095.9 6204.4 6277.4 429.6 430.2 440.6 440.9 444.7 4201.3 4335.9 4377.3 4381.3 4414.7 10002.5 10235.3 10312.1 10322.6 10333.5 10056.1 10115.4 10204.3 10576.6 10586.8 7910.9 7952.9 7984.8 8010.2 8040.0 5939.8 6048.0 6049.6 6144.2 6195.6 9279.5 9413.2 9581.6 9634.9 9644.9 9635.7 9659.3 9699.3 9795.1 9803.7 7510.1 7531.4 7610.8 7642.0 7647.0 8184.5 8196.4 8260.6 8278.4 8322.0 6940.7 7250.7 7437.1 7455.6 7531.3 7170.1 7227.4 7361.4 7456.6 7501.9 6766.9 6808.7 6839.1 6839.4 6843.7 4962.2 5081.6 5184.6 5204.4 5206.1 5281.1 5360.7 5423.9 5459.9 5487.9 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | [#4] prototype.html Cycles:180 Average:4590.25 Median:5231.28 stddev:1362.35 (26.0%) stddev-sans-first:1327.95 22:20:16 INFO - PROCESS | 18813 | Values: 321.1 328.6 332.9 337.6 338.0 5252.9 5347.3 5352.7 5367.2 5401.8 5217.7 5269.6 5278.7 5283.9 5298.8 3944.5 3950.4 4037.6 4100.4 4112.7 4048.4 4080.4 4092.4 4100.3 4104.6 3877.5 4046.3 4058.3 4063.7 4065.5 3806.9 3960.8 3975.2 4097.2 4125.9 3624.7 3631.4 3638.3 3644.8 3650.7 5150.2 5153.8 5160.6 5161.9 5171.9 4529.2 4695.4 4696.4 4703.5 4714.0 5449.9 5468.6 5485.6 5491.2 5520.6 3790.4 3912.7 3947.7 3962.7 3963.7 5673.5 5717.7 5748.8 5787.7 5792.9 4010.3 4010.6 4011.5 4019.7 4020.8 3832.0 4065.6 4075.8 4080.3 4115.7 4536.2 4590.2 4593.5 4613.2 4626.1 5496.0 5503.8 5542.9 5543.6 5576.8 1354.7 1391.4 1409.5 1411.0 1416.6 874.8 886.3 898.9 900.4 906.2 5226.9 5292.3 5294.6 5309.1 5320.4 5651.3 5661.5 5669.5 5696.5 5702.8 5074.6 5289.6 5320.8 5344.7 5386.7 2985.7 3020.0 3035.6 3042.3 3057.7 5824.9 5826.4 5845.2 5855.8 5867.4 5593.0 5813.7 5814.3 5829.9 5833.6 5728.7 5745.5 5758.6 5760.6 5797.4 5442.9 5622.4 5643.3 5646.0 5687.4 5613.9 5635.9 5644.6 5660.4 5674.7 5815.0 5824.7 5844.3 5861.5 5862.6 3596.9 3634.0 3643.3 3678.0 3699.9 5024.9 5081.6 5102.3 5116.0 5132.9 4950.5 4981.7 4991.9 5002.7 5004.9 4953.5 4999.4 5113.9 5582.8 5591.5 5235.6 5457.7 5460.8 5518.6 5520.5 5388.9 5428.2 5449.0 5454.0 5471.3 5466.4 5484.9 5507.4 5511.9 5516.9 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - PROCESS | 18813 | [#5] yui.html Cycles:180 Average:19490.68 Median:4796.24 stddev:27638.10 (576.2%) stddev-sans-first:27679.85 22:20:16 INFO - PROCESS | 18813 | Values: 760.3 777.0 790.3 799.5 835.6 14126.6 14400.4 14898.1 15413.0 15422.2 14701.7 14794.8 14906.5 14928.0 14935.9 674.5 798.6 811.7 824.1 825.4 811.9 822.8 822.9 824.2 825.4 865.1 870.6 871.3 871.3 872.3 706.4 833.5 856.8 866.3 866.4 626.5 626.7 627.2 627.5 627.8 12484.7 12487.2 12509.0 12538.3 12542.2 13861.7 14250.0 14350.4 14513.6 14711.5 12544.7 12708.2 12729.4 12755.4 12773.0 4329.1 4403.7 4419.9 4439.2 4515.3 71136.6 71273.6 72525.6 72721.6 76467.0 10866.7 11133.8 11222.6 11256.6 11456.4 20623.7 20691.1 20692.3 20715.6 20745.5 8007.4 8243.7 8381.2 8396.6 8526.6 4744.7 4766.8 4847.8 4920.5 4939.8 3513.1 3617.0 3617.4 3619.2 3621.9 592.4 596.5 603.0 604.4 604.8 567.8 680.8 690.1 722.2 737.6 9147.8 9209.4 9209.6 9392.9 9501.7 674.5 732.3 742.4 788.7 793.1 4264.2 4646.3 4719.6 4733.7 4825.7 70538.6 74278.0 109243.0 117314.0 117813.0 97914.0 98630.0 99349.5 99519.0 99729.0 70284.3 70740.0 72716.6 89681.6 90253.0 9140.9 9611.9 9690.0 9707.0 9806.9 37060.8 37657.4 52265.8 52359.5 52544.7 62878.4 64509.4 64778.0 67786.0 72977.6 12182.9 12308.7 12440.2 13226.4 13776.5 13741.3 13917.4 14074.6 14263.6 14609.9 12822.8 13159.3 13250.1 13262.7 13290.3 13006.7 13082.5 13095.6 13550.9 13567.1 12495.3 12907.4 12977.4 13166.1 13207.3 10038.4 10191.8 10233.8 10434.1 10573.4 10788.5 11067.0 11218.7 11230.0 11298.6 22:20:16 INFO - PROCESS | 18813 | -------- Summary: end -------- 22:20:16 INFO - PROCESS | 18813 | 22:20:16 INFO - TEST-INFO | 18813: exit 0 22:20:16 INFO - TEST-OK | dromaeo_css | took 1114023ms 22:20:16 INFO - TEST-START | kraken 22:20:16 INFO - operating with platform_type : linux_ 22:20:16 INFO - Initialising browser for kraken test... 22:20:16 INFO - TEST-INFO | started process 18994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfJhMfN/profile http://localhost:60388/getInfo.html) 22:20:21 INFO - PROCESS | 18994 | __metrics Screen width/height:1600/1200 22:20:21 INFO - PROCESS | 18994 | colorDepth:24 22:20:21 INFO - PROCESS | 18994 | Browser inner width/height: 1024/697 22:20:21 INFO - PROCESS | 18994 | __metrics 22:20:22 ERROR - PROCESS | 18994 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:20:23 INFO - TEST-INFO | 18994: exit 0 22:20:23 INFO - Browser initialized. 22:20:23 INFO - Running cycle 1/1 for kraken test... 22:20:23 INFO - TEST-INFO | started process 19074 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfJhMfN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:34 INFO - PROCESS | 19074 | RSS: Main: 156459008 22:20:34 INFO - PROCESS | 19074 | 22:20:37 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60388/tests/kraken/driver.html?testName=audio-beat-detection) 22:20:38 INFO - PROCESS | 19074 | RSS: Main: 214675456 22:20:38 INFO - PROCESS | 19074 | 22:20:40 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60388/tests/kraken/driver.html?testName=audio-dft) 22:20:41 INFO - PROCESS | 19074 | RSS: Main: 201609216 22:20:41 INFO - PROCESS | 19074 | 22:20:44 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60388/tests/kraken/driver.html?testName=audio-fft) 22:20:45 INFO - PROCESS | 19074 | RSS: Main: 269135872 22:20:45 INFO - PROCESS | 19074 | 22:20:47 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60388/tests/kraken/driver.html?testName=audio-oscillator) 22:20:47 INFO - PROCESS | 19074 | RSS: Main: 179712000 22:20:47 INFO - PROCESS | 19074 | 22:20:50 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60388/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:20:50 INFO - PROCESS | 19074 | RSS: Main: 183709696 22:20:50 INFO - PROCESS | 19074 | 22:20:53 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60388/tests/kraken/driver.html?testName=imaging-darkroom) 22:20:54 INFO - PROCESS | 19074 | RSS: Main: 219734016 22:20:54 INFO - PROCESS | 19074 | 22:20:57 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60388/tests/kraken/driver.html?testName=imaging-desaturate) 22:20:58 INFO - PROCESS | 19074 | RSS: Main: 220680192 22:20:58 INFO - PROCESS | 19074 | 22:21:01 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60388/tests/kraken/driver.html?testName=json-parse-financial) 22:21:01 INFO - PROCESS | 19074 | RSS: Main: 220360704 22:21:01 INFO - PROCESS | 19074 | 22:21:03 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60388/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:03 INFO - PROCESS | 19074 | RSS: Main: 206610432 22:21:03 INFO - PROCESS | 19074 | 22:21:05 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:06 INFO - PROCESS | 19074 | RSS: Main: 210563072 22:21:06 INFO - PROCESS | 19074 | 22:21:08 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:21:09 INFO - PROCESS | 19074 | RSS: Main: 216805376 22:21:09 INFO - PROCESS | 19074 | 22:21:11 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:21:12 INFO - PROCESS | 19074 | RSS: Main: 224337920 22:21:12 INFO - PROCESS | 19074 | 22:21:14 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:21:15 INFO - PROCESS | 19074 | RSS: Main: 222199808 22:21:15 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | Cycle 1(1): loaded http://localhost:60388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60388/tests/kraken/driver.html?testName=ai-astar) 22:21:17 INFO - PROCESS | 19074 | RSS: Main: 264159232 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | __start_tp_report 22:21:17 INFO - PROCESS | 19074 | _x_x_mozilla_page_load 22:21:17 INFO - PROCESS | 19074 | _x_x_mozilla_page_load_details 22:21:17 INFO - PROCESS | 19074 | |i|pagename|runs| 22:21:17 INFO - PROCESS | 19074 | |0;ai-astar;110;109;109;108;110;109;109;110;110;110 22:21:17 INFO - PROCESS | 19074 | |1;audio-beat-detection;170;125;131;181;136;149;155;127;192;128 22:21:17 INFO - PROCESS | 19074 | |2;audio-dft;160;160;159;161;161;161;162;162;160;162 22:21:17 INFO - PROCESS | 19074 | |3;audio-fft;73;74;75;120;74;76;74;74;97;75 22:21:17 INFO - PROCESS | 19074 | |4;audio-oscillator;86;85;114;85;85;86;120;85;85;138 22:21:17 INFO - PROCESS | 19074 | |5;imaging-gaussian-blur;117;116;116;117;116;116;117;116;116;117 22:21:17 INFO - PROCESS | 19074 | |6;imaging-darkroom;146;146;146;147;146;145;145;145;145;145 22:21:17 INFO - PROCESS | 19074 | |7;imaging-desaturate;94;94;95;95;95;94;96;95;95;95 22:21:17 INFO - PROCESS | 19074 | |8;json-parse-financial;54;52;52;53;52;53;53;52;53;52 22:21:17 INFO - PROCESS | 19074 | |9;json-stringify-tinderbox;62;62;62;63;63;62;62;63;62;62 22:21:17 INFO - PROCESS | 19074 | |10;stanford-crypto-aes;78;79;77;85;79;81;84;81;111;79 22:21:17 INFO - PROCESS | 19074 | |11;stanford-crypto-ccm;124;123;130;118;121;119;131;128;143;121 22:21:17 INFO - PROCESS | 19074 | |12;stanford-crypto-pbkdf2;155;156;157;161;157;160;155;157;157;159 22:21:17 INFO - PROCESS | 19074 | |13;stanford-crypto-sha256-iterative;66;64;63;62;66;62;63;69;62;62 22:21:17 INFO - PROCESS | 19074 | __end_tp_report 22:21:17 INFO - PROCESS | 19074 | __start_cc_report 22:21:17 INFO - PROCESS | 19074 | _x_x_mozilla_cycle_collect,1639 22:21:17 INFO - PROCESS | 19074 | __end_cc_report 22:21:17 INFO - PROCESS | 19074 | __startTimestamp1464931277409__endTimestamp 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | ------- Summary: start ------- 22:21:17 INFO - PROCESS | 19074 | Number of tests: 14 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 22:21:17 INFO - PROCESS | 19074 | Values: 110.0 109.0 109.0 108.0 110.0 109.0 109.0 110.0 110.0 110.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:152.00 stddev:24.36 (16.0%) stddev-sans-first:24.68 22:21:17 INFO - PROCESS | 19074 | Values: 170.0 125.0 131.0 181.0 136.0 149.0 155.0 127.0 192.0 128.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 22:21:17 INFO - PROCESS | 19074 | Values: 160.0 160.0 159.0 161.0 161.0 161.0 162.0 162.0 160.0 162.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#3] audio-fft Cycles:10 Average:81.20 Median:74.50 stddev:15.40 (20.7%) stddev-sans-first:16.04 22:21:17 INFO - PROCESS | 19074 | Values: 73.0 74.0 75.0 120.0 74.0 76.0 74.0 74.0 97.0 75.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:85.00 stddev:19.61 (23.1%) stddev-sans-first:20.40 22:21:17 INFO - PROCESS | 19074 | Values: 86.0 85.0 114.0 85.0 85.0 86.0 120.0 85.0 85.0 138.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:21:17 INFO - PROCESS | 19074 | Values: 117.0 116.0 116.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 22:21:17 INFO - PROCESS | 19074 | Values: 146.0 146.0 146.0 147.0 146.0 145.0 145.0 145.0 145.0 145.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 22:21:17 INFO - PROCESS | 19074 | Values: 94.0 94.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 95.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:0.70 (1.3%) stddev-sans-first:0.53 22:21:17 INFO - PROCESS | 19074 | Values: 54.0 52.0 52.0 53.0 52.0 53.0 53.0 52.0 53.0 52.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 22:21:17 INFO - PROCESS | 19074 | Values: 62.0 62.0 62.0 63.0 63.0 62.0 62.0 63.0 62.0 62.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:80.00 stddev:10.02 (12.5%) stddev-sans-first:10.44 22:21:17 INFO - PROCESS | 19074 | Values: 78.0 79.0 77.0 85.0 79.0 81.0 84.0 81.0 111.0 79.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:126.00 stddev:7.52 (6.0%) stddev-sans-first:7.95 22:21:17 INFO - PROCESS | 19074 | Values: 124.0 123.0 130.0 118.0 121.0 119.0 131.0 128.0 143.0 121.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.40 Median:157.00 stddev:2.01 (1.3%) stddev-sans-first:1.94 22:21:17 INFO - PROCESS | 19074 | Values: 155.0 156.0 157.0 161.0 157.0 160.0 155.0 157.0 157.0 159.0 22:21:17 INFO - PROCESS | 19074 | 22:21:17 INFO - PROCESS | 19074 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:63.50 stddev:2.38 (3.7%) stddev-sans-first:2.40 22:21:17 INFO - PROCESS | 19074 | Values: 66.0 64.0 63.0 62.0 66.0 62.0 63.0 69.0 62.0 62.0 22:21:17 INFO - PROCESS | 19074 | -------- Summary: end -------- 22:21:17 INFO - PROCESS | 19074 | 22:21:18 INFO - TEST-INFO | 19074: exit 0 22:21:18 INFO - TEST-OK | kraken | took 61533ms 22:21:18 INFO - SUITE-END | took 1175s 22:21:18 INFO - Completed test suite (00:19:35) 22:21:18 INFO - javascript benchmark 22:21:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3172.6510083890767, 3184.3949649566584, 3191.29791445882, 3217.372612340592, 3257.2182676157713, 5317.601179911512, 5353.170258609922, 5427.674339539631, 5560.805371811987, 5581.302337207854, 5466.726663666821, 5472.890542189154, 5495.807646732365, 5514.503694667479, 5565.6104072714925, 4875.341828853104, 4895.55939965403, 4977.377827771528, 4980.327655766479, 5155.639105262633, 5039.521245481674, 5064.594832413398, 5085.0, 5111.05445492585, 5111.769970351324, 4834.540718631724, 4919.950800492003, 4930.087933732271, 4943.233798761198, 4987.351644286256, 4939.135651261015, 4946.629002824802, 5021.874453138701, 5025.547700706954, 5067.0879241736475, 4811.8075276988875, 4961.677490963076, 4985.775640096168, 5002.1746411842005, 5037.773300201499, 5333.439988801198, 5384.91922621161, 5392.325959255093, 5423.620346575742, 5461.808836690737, 5902.468777809975, 5922.466977972003, 5931.317898441648, 5933.703314834241, 5936.406359364072, 5709.514691251248, 5811.941880581182, 5865.296164460249, 5975.551833612496, 6022.217111775485, 5536.667799931976, 5553.972230138864, 5565.30433695788, 5568.387477377485, 5580.051391263495, 6034.948568595861, 6051.8946986238425, 6056.547619047619, 6107.96946015276, 6112.235970503687, 5608.523275521545, 5730.226419433323, 5742.885142297131, 5773.509251713567, 5833.125031245262, 5886.558508111909, 5892.086726557391, 5941.465268125889, 5946.791862284799, 5946.940530594725, 5811.622244554091, 5883.794067207627, 5883.941160588425, 5886.499647529922, 5894.557908156819, 5644.435556444323, 5745.597808153389, 5756.280464941882, 5788.305403351625, 5865.618734782225, 4424.977875110604, 4459.44256967879, 4469.9553004469535, 4477.462704475483, 4513.729176249435, 3948.8420463181724, 3950.0914789598555, 3987.5414327352146, 4018.3570628699267, 4039.2325458162854, 5403.054465468527, 5475.069238229431, 5510.0632892408385, 5533.004059269289, 5534.225208470817, 5960.493358064527, 5961.970190149022, 5965.224520812267, 5974.283086029617, 6002.819915402458, 5384.326959130109, 5550.722463876871, 5562.082256427725, 5562.803997140634, 5570.805021824217, 4966.850994470172, 5057.8735531612, 5083.87290317745, 5104.336436263262, 5153.0, 6097.298810636834, 6108.816735497942, 6110.114033465123, 6116.816495505142, 6130.0, 5696.373398926197, 5932.970335148297, 5934.703264836828, 5942.227510423617, 5944.256967879015, 5980.551458640706, 6029.1559181715475, 6035.939640603538, 6085.786997455068, 6098.969505152446, 5750.942490575041, 5773.249477568057, 5773.278340207474, 5777.335606405317, 5794.333651630117, 5764.538836892954, 5822.388649191774, 5829.183914251923, 5865.677387743715, 5878.794242201502, 5680.573956953162, 5744.080947048619, 5761.308642962704, 5766.135079738073, 5780.075187969905, 5754.798582049608, 5760.769569217185, 5760.798372056958, 5765.798197063083, 5768.567357448124, 5515.669059856422, 5573.0, 5628.015097358028, 5668.0364338063455, 5679.9148012779015, 5806.158107074535, 5842.065269556851, 5847.502962248256, 5848.473637372657, 5856.0, 5638.182463542681, 5645.943540564542, 5648.858778530405, 5649.491545760901, 5649.915251271316], "value": 5412.690824730716, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.4733163818382, 613.2058983616216, 620.9999999999999, 636.1856823266221, 637.8501052252722, 34641.48037779435, 37144.442833357454, 37273.99999999996, 37287.0, 44528.55471445285, 42154.7892260539, 46206.07587848241, 46331.768341158335, 46429.30356044662, 46440.76779616105, 8673.089325620798, 8842.778930526756, 8844.911550884473, 8848.2478989286, 8853.0, 8625.137486251388, 8821.647134114659, 8826.691065812698, 8826.823463530725, 8834.116588341147, 8800.383973121883, 8822.602982865754, 8841.16008979146, 8849.867251991192, 8867.822643547126, 8774.561271936409, 8816.911830881672, 8825.867611985857, 8827.16141966512, 8836.381453298272, 8730.257928076122, 8828.8675669865, 8832.646694132227, 8835.072317406644, 8837.513936733478, 42654.78672606617, 43165.0, 43210.351844722354, 43330.0, 43407.13185736299, 3519.6480351964597, 3591.6408359164134, 3671.724620653462, 3884.1843212925037, 4075.816588253506, 32264.838675806706, 32277.51583726246, 32660.836695816368, 32678.509822352684, 34359.0, 3795.8671446499516, 4031.113155105872, 4281.186574550826, 4332.415123958257, 4427.180971520279, 324886.0, 325647.0, 327526.0, 327951.0, 328952.9999999976, 60808.39191608116, 61121.0, 61239.6938015316, 61310.0, 61344.69327653333, 65114.0, 65143.34856651467, 65175.674121630036, 65242.347576524575, 65262.673686631264, 16908.239129239213, 17144.828551714323, 17175.484735457958, 17183.742243866353, 17187.226574804165, 11962.461689224006, 11967.162298639012, 11968.10239232061, 11979.34113623759, 11996.100292478099, 17078.4033884259, 18513.7222941656, 18566.443006709822, 18657.53356166107, 18677.346292879683, 6333.429991300713, 6610.0745895574655, 6632.137822083195, 6721.26066132725, 6723.428508576728, 839.8320335932838, 970.8932017478072, 1066.2376400873413, 1124.2804605052772, 1192.66605350502, 31478.623128680934, 32670.346593068007, 32811.17972050718, 33110.66889331124, 33293.667063329056, 1088.2708585247904, 1145.2212495503138, 1174.8296497008057, 1205.7166565857017, 1257.6792917805901, 17120.74318885218, 17337.0, 17359.392421265195, 17459.738103928452, 17510.562235944202, 479775.0, 481376.0, 490267.0, 492825.0, 492893.0, 58975.0, 59155.0, 59237.40762592319, 59404.10893836596, 59441.70279148577, 1127.6673381352357, 1234.2224398628807, 1348.426918559628, 1358.2801115409047, 1391.8816900563363, 29912.25219369533, 33969.0, 34328.14179645429, 34539.63074964193, 34657.0, 53587.73206133944, 53866.0, 54132.0, 54285.0, 54416.0, 478104.0, 491630.0, 492283.0, 493713.0, 493860.0, 35900.82049589735, 48322.27516587183, 48504.55200987269, 49856.750716246184, 49956.0, 50118.498815011386, 50262.246066309766, 50711.74644126903, 50805.237921431944, 51066.0, 40294.39558406685, 42108.0, 42138.15723685448, 43106.78446607747, 43234.35148472712, 40910.0, 40914.38628420635, 41405.0, 41625.167496649294, 41715.16569668529, 41280.1743965113, 41528.1694366117, 41793.79103104465, 41985.370219447344, 42296.0, 36583.81708091549, 38408.807955960045, 38891.80554097211, 39004.219915602094, 39259.41110883282, 28817.85591072031, 28976.13071607855, 29279.0, 29343.85328073346, 29550.704492954796], "value": 20545.377490251187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.8464614154338, 387.41306920016183, 389.6869352583867, 397.4257198348386, 409.77052850403777, 14069.610255851618, 16094.195290235471, 18817.62364752706, 19520.90239548802, 19548.218071277166, 13744.58766237012, 15282.69434611307, 15305.540833774976, 15529.611759706013, 15768.0, 3913.4129880517867, 4059.776712280823, 4064.1465292288653, 4064.715469917107, 5701.57238207134, 5519.862003449905, 5615.5507559395355, 5638.295213098363, 5648.378678345379, 5654.717264136797, 4116.361963895601, 5570.832875013756, 5598.328200615912, 5612.270404847364, 5624.775008999655, 5946.881062378749, 6212.068189771525, 6212.937870621304, 6320.494360451153, 6331.525135614801, 5341.118715411992, 5488.121900495901, 5528.170774383834, 5548.889022219573, 5556.777728890839, 21781.455463613376, 22580.774192258195, 22603.434914127112, 22803.31590052301, 22939.88530057356, 2309.145616122046, 2520.659710939018, 2531.329197762576, 2616.3459135216194, 2849.216465471991, 13381.46474141043, 13555.25446100464, 13681.589552313348, 14018.228997405138, 14231.715365692731, 1876.9436916892514, 1919.1008325071484, 1928.0, 1954.7556555430572, 1966.0366420453875, 75708.0, 76171.23828761697, 76352.61823690846, 77664.61167694181, 78686.60656696737, 9191.954040229755, 9816.705498834903, 10209.642662506925, 10225.795484090426, 10268.948655256676, 14005.929970350287, 14083.859161408254, 14102.224377659328, 14127.78808317876, 14133.85866141346, 8040.557769322626, 8071.717489887943, 8126.9593652032545, 8151.0, 8266.338692904554, 6604.966975165094, 7612.7716168515035, 7826.099998500132, 7989.041315042232, 8124.0, 6316.873662526724, 6418.24015673265, 6464.288928217892, 6470.12653291809, 6544.149260596188, 1767.1341042889148, 1793.1213705284317, 1819.8817076890225, 1878.8402985746366, 1888.0937150167997, 898.3621628643737, 1029.1920842139034, 1029.5727273181697, 1072.5924148823397, 1106.9889301106887, 18160.55066508714, 18810.24759009652, 18975.71536426955, 19185.8081419184, 19194.0, 1015.7968406318765, 1022.0903395977585, 1046.9214808889365, 1075.537518866879, 1083.5124194112682, 10647.254692171473, 11076.335419874833, 11097.38964356968, 11110.61112861046, 11168.553257869595, 437303.0, 437673.0, 442278.0, 446822.0, 454558.0, 79397.0, 80074.59962700149, 80598.19401805908, 81668.0, 82983.0, 1038.870141232346, 1056.3503445381189, 1106.9391183484986, 1123.3203911633507, 1127.1940562497853, 16862.747058793884, 16946.491605251864, 17161.141942903054, 17253.65492690114, 17383.82616173822, 37515.43726844045, 37818.62181378151, 38125.4281185788, 38732.61267387403, 39465.210695786496, 49961.0, 52699.419017429536, 52931.735341323045, 53096.20355694745, 53167.20249196343, 8393.95803020981, 8396.286315663237, 8506.489610623383, 8549.27331176832, 8660.17728315809, 12632.368381581067, 12645.494180232688, 13135.211887286794, 13160.670983225113, 13245.867541324464, 11176.720581985515, 11328.32030078198, 11388.430578470878, 11840.407979601157, 11864.82202766942, 10996.505157268102, 11221.775564488828, 11278.436078195893, 11506.137039722222, 11656.941715291709, 11766.235194712317, 11790.351530665896, 11996.040316774812, 12008.39958002114, 12062.095342849498, 8260.0, 8409.075001749925, 8438.451500652438, 8604.870926936226, 8614.698485552975, 11194.384308863091, 11388.544458221579, 11842.407879605813, 11892.702682433008, 11935.104867134825], "value": 9061.940636585225, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.150623600895, 1478.1722235548093, 1511.7959075524805, 1670.1816110106051, 1774.0508827777142, 8277.627506762195, 8622.310215182786, 8631.956840215798, 8857.202851743341, 8872.600732967016, 8599.054104048566, 8768.386212965095, 8795.076516965722, 8803.295736341093, 8816.647334106628, 1972.8816271023713, 2211.016097836455, 2262.6266666000092, 2273.818094552432, 2292.1404473322505, 2334.3697201755517, 2338.8479748816358, 2339.7075365579303, 2343.4024323797407, 2346.038124369009, 2298.092253559843, 2315.513742114158, 2323.1288266899915, 2325.430269583954, 2327.5228578141523, 1938.6122775444967, 2149.6990421341025, 2150.4193867655804, 2223.499712564676, 2225.3324002799095, 2222.922197723088, 2242.338510139513, 2275.977240227593, 2279.9968014073756, 2282.3609389371004, 8730.257928076122, 8805.427647202976, 8824.426412283181, 8832.735017949408, 8836.028036915934, 5077.898442031139, 5202.947970520322, 5206.297149884793, 5207.583393328525, 5213.165893457067, 2935.3248752786767, 2990.8055976361684, 3067.2331917020747, 3086.1975886269734, 3142.7800053996, 1035.746242170673, 1038.2005855491304, 1050.385524468187, 1051.2168434516334, 1061.8460323253084, 12119.454624541828, 12141.210821296587, 12152.878471215352, 12157.392130393533, 12174.452149653198, 4432.468103827497, 4434.024514606781, 4551.658625603093, 4558.521355257651, 4568.428946381702, 10337.534810933526, 10342.120919721758, 10355.223358248162, 10370.0, 10376.533056012497, 5113.8721531961255, 5176.9741151294, 5178.663386879841, 5188.351456067991, 5190.429052804188, 4170.082581831992, 4662.277347011219, 4780.760961951958, 4801.063792560487, 4837.975810120997, 3942.211557688474, 3987.4616926715103, 4043.7775922323967, 4096.40602112692, 4104.281750693676, 1243.055277988717, 1270.510853321478, 1272.8027155790867, 1275.4196840437578, 1293.5149319005372, 873.8208667223204, 960.7886265021684, 981.9656312029044, 986.2455221755363, 1000.7798284377425, 5962.22491076166, 5977.940220597825, 6095.939040609625, 6204.410580994889, 6277.372262773686, 429.6305177547307, 430.18265295937346, 440.583648452216, 440.9074094440139, 444.65317052698947, 4201.306784380543, 4335.934960975588, 4377.343398490252, 4381.3427985801745, 4414.713043652152, 10002.49987500622, 10235.334703244265, 10312.071913527818, 10322.587096516056, 10333.534990925426, 10056.094951454405, 10115.39307641544, 10204.285700000928, 10576.629817956335, 10586.841197382046, 7910.8813367798375, 7952.920470795218, 7984.840303193903, 8010.239027292398, 8040.0, 5939.821805345847, 6047.969760151171, 6049.637021778707, 6144.201253837151, 6195.628262304276, 9279.489628070522, 9413.246940244628, 9581.568829402555, 9634.855477167708, 9644.855327169958, 9635.662751803653, 9659.275554333593, 9699.321047526888, 9795.06946840049, 9803.656872009446, 7510.098788145458, 7531.3598344141365, 7610.847783044419, 7641.961790191236, 7646.9617651911385, 8184.54984975832, 8196.385271104571, 8260.586970651564, 8278.379121565786, 8322.0, 6940.687669055005, 7250.709971601077, 7437.107547094383, 7455.589942553212, 7531.322181003736, 7170.139583250043, 7227.421806255592, 7361.374283185989, 7456.6271686414375, 7501.912222727565, 6766.8646627068165, 6808.65956702153, 6839.110915580943, 6839.350261725128, 6843.692033858389, 4962.156433406401, 5081.568066714371, 5184.559312458483, 5204.427512973645, 5206.062908676475, 5281.128613778684, 5360.655901415086, 5423.864403389947, 5459.918101228405, 5487.917681234864], "value": 4457.1709964585925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.1057205682176, 328.5777775558408, 332.90345799718085, 337.5965720963447, 337.972962163027, 5252.896891652753, 5347.304850369452, 5352.678839269646, 5367.2485851980655, 5401.78392864286, 5217.660852044614, 5269.62989622698, 5278.736063196834, 5283.867903302419, 5298.761555729991, 3944.4872166618297, 3950.407438884176, 4037.5962403759536, 4100.36444351126, 4112.691548133887, 4048.360414032322, 4080.3675430308203, 4092.3656833190753, 4100.261952848501, 4104.630583247507, 3877.4765406670163, 4046.312126938413, 4058.269511487931, 4063.6545893599077, 4065.512138543379, 3806.9252257519443, 3960.8019599020076, 3975.2049590081956, 4097.242010228118, 4125.864343099266, 3624.691901188402, 3631.4189729643394, 3638.2905333459985, 3644.7995360255177, 3650.689691376236, 5150.175971844487, 5153.768080436389, 5160.6129540284255, 5161.948380516184, 5171.948280517222, 4529.184746745585, 4695.389599352096, 4696.365990591295, 4703.529647035303, 4714.0, 5449.8827740313145, 5468.589855760794, 5485.573750824775, 5491.203775452577, 5520.641158324696, 3790.431435284702, 3912.68698504119, 3947.703922205846, 3962.702797290186, 3963.7027222958395, 5673.489385955242, 5717.684932465556, 5748.770049198027, 5787.652740835563, 5792.9420705793245, 4010.2981978153707, 4010.5588385277597, 4011.498562679665, 4019.658329042006, 4020.7587544746825, 3831.9616803832164, 4065.59344065597, 4075.8165882534763, 4080.2655522006326, 4115.662409716854, 4536.183486972311, 4590.196715574761, 4593.540645935446, 4613.192691279013, 4626.1441633297945, 5496.048902639585, 5503.844192719494, 5542.891421715673, 5543.61194716366, 5576.832695019156, 1354.7290541891662, 1391.4295138993166, 1409.5348534983636, 1410.978835317471, 1416.5750274917484, 874.790050387915, 886.2909672262165, 898.925783688499, 900.3607438718452, 906.1889608800219, 5226.92159617606, 5292.285541451932, 5294.602904782157, 5309.070912590281, 5320.441353657888, 5651.2935883014625, 5661.49970257882, 5669.461401166883, 5696.515796157373, 5702.800401985911, 5074.644774865724, 5289.603279754034, 5320.791871373414, 5344.732763361894, 5386.680263335429, 2985.7312841844337, 3019.9849000755294, 3035.5598438226334, 3042.2546476113494, 3057.685195366014, 5824.854378640568, 5826.359100498942, 5845.210896528999, 5855.795047173328, 5867.442592953748, 5592.979790912861, 5813.680247586423, 5814.3313518945015, 5829.94170058294, 5833.649981001154, 5728.742206600713, 5745.511631511285, 5758.625689330264, 5760.567957403212, 5797.449242321973, 5442.918356224739, 5622.381538030895, 5643.294588176478, 5645.971770141123, 5687.43125687428, 5613.915791263053, 5635.8591035224445, 5644.629255712529, 5660.37735849048, 5674.687892165971, 5815.0, 5824.737886795019, 5844.26946631671, 5861.501772349398, 5862.618929769551, 3596.856125755045, 3633.964320168739, 3643.289558536166, 3677.9816100919325, 3699.9260014800093, 5024.899502010013, 5081.5699402902155, 5102.336696229615, 5116.0, 5132.948670513397, 4950.45544990058, 4981.726005069611, 4991.9001619966675, 5002.724850133278, 5004.874878128076, 4953.479884612064, 4999.425525643995, 5113.923291150561, 5582.7766889323975, 5591.524720398812, 5235.63873392913, 5457.672539647634, 5460.771326451517, 5518.641288316247, 5520.530754885719, 5388.949154914754, 5428.212909128233, 5448.972755136199, 5453.972730136483, 5471.261379713687, 5466.426025267289, 5484.91772623403, 5507.394186639386, 5511.917321240265, 5516.889662206813], "value": 4156.257326443019, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.3043215457857, 776.9533827970325, 790.2966359939653, 799.4994438264736, 835.5822088955522, 14126.576202713923, 14400.423983040679, 14898.106113633175, 15413.0, 15422.151781652005, 14701.705965880676, 14794.778078328834, 14906.478273260438, 14928.0, 14935.925320373382, 674.5413119079025, 798.6406117247233, 811.6591031766643, 824.085265355458, 825.4387016828554, 811.8741595052746, 822.8189798244376, 822.9094799572042, 824.221111050058, 825.3685930263354, 865.1219012702112, 870.5908223135116, 871.2898987325341, 871.2942516561574, 872.2672954718035, 706.4101475268131, 833.4665813879122, 856.7900864288227, 866.2766589897411, 866.3935245328264, 626.4706323156925, 626.7117126121989, 627.2003195925187, 627.4697880291146, 627.7645882793952, 12484.687882803002, 12487.188332758433, 12509.0, 12538.310392928386, 12542.1847579907, 13861.722765544733, 14250.0, 14350.425982960669, 14513.637159071002, 14711.485098021518, 12544.651086322878, 12708.173968692005, 12729.427175777113, 12755.362231888368, 12772.997686733182, 4329.112531930978, 4403.735775853427, 4419.9337009944875, 4439.156560253541, 4515.254982927813, 71136.64431677808, 71273.64363178203, 72525.6373718128, 72721.63639181823, 76466.99999999945, 10866.728331791768, 11133.832992505118, 11222.607208747819, 11256.606018789467, 11456.369899655432, 20623.69064464034, 20691.06890189945, 20692.275770347966, 20715.585688286148, 20745.481362965744, 8007.359411247086, 8243.670253189925, 8381.161883811692, 8396.60073581033, 8526.57367131641, 4744.667873248908, 4766.785494652749, 4847.781849816767, 4920.532549407801, 4939.827106051271, 3513.0865974846724, 3617.04148400674, 3617.439296908931, 3619.2218672985437, 3621.8732344367822, 592.4016743089425, 596.483728420956, 602.9698515074228, 604.3895665377912, 604.7973928733924, 567.7501899164355, 680.8059702984625, 690.0925283252507, 722.2055738687402, 737.6274981134493, 9147.81704365909, 9209.355345125778, 9209.605502569246, 9392.8591071134, 9501.714948551555, 674.4546594645849, 732.3043109046428, 742.406075139886, 788.6766425765409, 793.131520984521, 4264.168487145039, 4646.256598944153, 4719.646026548023, 4733.715977041388, 4825.686330388514, 70538.64730676313, 74278.0, 109243.0, 117314.0, 117813.0, 97914.0, 98630.0, 99349.50325248472, 99519.0, 99729.0, 70284.29715702879, 70740.0, 72716.63641681758, 89681.55159224162, 90253.0, 9140.908590914272, 9611.903880961381, 9689.962902817279, 9707.0, 9806.901930980599, 37060.81469592635, 37657.4351384724, 52265.750949573274, 52359.47640523699, 52544.7372763149, 62878.371216287254, 64509.35490645221, 64778.0, 67786.0, 72977.6351118241, 12182.878171218174, 12308.722216948949, 12440.19138755978, 13226.4048117833, 13776.517821876587, 13741.312934353442, 13917.373718182504, 14074.648133796736, 14263.572092837232, 14609.904257180542, 12822.763222196943, 13159.276239806519, 13250.072494925264, 13262.734745304846, 13290.335483225606, 13006.674833128869, 13082.476700932237, 13095.607131786062, 13550.864491355354, 13567.118137321042, 12495.250284982567, 12907.354632268161, 12977.41601627948, 13166.078374513692, 13207.273599952096, 10038.447885366375, 10191.847122293024, 10233.846492302473, 10434.113100386552, 10573.365598064142, 10788.514516846604, 11067.03315751665, 11218.66344009681, 11230.0, 11298.60454884075], "value": 7376.881695504091, "unit": "score"}], "name": "dromaeo_css", "value": 7186.295938697336, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 109.0, 108.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 125.0, 131.0, 181.0, 136.0, 149.0, 155.0, 127.0, 192.0, 128.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 161.0, 161.0, 161.0, 162.0, 162.0, 160.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 120.0, 74.0, 76.0, 74.0, 74.0, 97.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 114.0, 85.0, 85.0, 86.0, 120.0, 85.0, 85.0, 138.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 53.0, 52.0, 53.0, 53.0, 52.0, 53.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 77.0, 85.0, 79.0, 81.0, 84.0, 81.0, 111.0, 79.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 130.0, 118.0, 121.0, 119.0, 131.0, 128.0, 143.0, 121.0], "value": 125.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 157.0, 161.0, 157.0, 160.0, 155.0, 157.0, 157.0, 159.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 63.0, 62.0, 66.0, 62.0, 63.0, 69.0, 62.0, 62.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1499.9000000000003, "alertThreshold": 2.0}]} 22:21:18 INFO - Return code: 0 22:21:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:21:18 ERROR - # TBPL SUCCESS # 22:21:18 INFO - Running post-action listener: _resource_record_post_action 22:21:18 INFO - ##### 22:21:18 INFO - ##### Finished run-tests step (success) 22:21:18 INFO - ##### 22:21:18 INFO - Running post-run listener: _resource_record_post_run 22:21:18 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 406732800; Read time: 152; Write time: 233064 22:21:18 INFO - TinderboxPrint: CPU usage
13.2% 22:21:18 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 152 22:21:18 INFO - TinderboxPrint: I/O write bytes / time
406,732,800 / 233,064 22:21:18 INFO - TinderboxPrint: CPU idle
8,275.1 (86.8%) 22:21:18 INFO - TinderboxPrint: CPU user
1,209.3 (12.7%) 22:21:18 INFO - TinderboxPrint: Swap in / out
0 / 0 22:21:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114696192; Read time: 0; Write time: 82364 22:21:18 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 8192; Write bytes: 292036608; Read time: 152; Write time: 150700 22:21:19 INFO - Running post-run listener: _upload_blobber_files 22:21:19 INFO - Blob upload gear active. 22:21:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:21:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:21:19 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'] 22:21:19 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 22:21:19 INFO - (blobuploader) - INFO - Open directory for files ... 22:21:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:21:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:21:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:23 INFO - (blobuploader) - INFO - Done attempting. 22:21:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:21:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:21:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:24 INFO - (blobuploader) - INFO - Done attempting. 22:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:21:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:21:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:21:24 INFO - (blobuploader) - INFO - Done attempting. 22:21:24 INFO - (blobuploader) - INFO - Iteration through files over. 22:21:24 INFO - Return code: 0 22:21:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:21:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:21:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3a6a383d5f2c4e7315987f37c6110153b8d42da3143f10129cbf47b9e0c3a22e4a1df039030d2ab252ca49ffa5070f9c09be1f32c169bbaff76bb7b0fdcb1ab7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27380f5955fa46da2681afa0f00baa69c7b4602afc3a72e3b770835a6365dd609c92b0e9965877c92f8ac04383a851a33af4bfcd92baf6ade4e1905324f7aefb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6e59d39efe612909bf960778179898f2211cdd56ebe22f9ec98823391ae99b5c6207cfe48e0b0c4042d70f5a888e96b19bbbf9f1771212ac6dc148983e937700"} 22:21:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:21:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:21:24 INFO - Contents: 22:21:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3a6a383d5f2c4e7315987f37c6110153b8d42da3143f10129cbf47b9e0c3a22e4a1df039030d2ab252ca49ffa5070f9c09be1f32c169bbaff76bb7b0fdcb1ab7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27380f5955fa46da2681afa0f00baa69c7b4602afc3a72e3b770835a6365dd609c92b0e9965877c92f8ac04383a851a33af4bfcd92baf6ade4e1905324f7aefb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6e59d39efe612909bf960778179898f2211cdd56ebe22f9ec98823391ae99b5c6207cfe48e0b0c4042d70f5a888e96b19bbbf9f1771212ac6dc148983e937700"} 22:21:24 INFO - Running post-run listener: copy_logs_to_upload_dir 22:21:24 INFO - Copying logs to upload dir... 22:21:24 INFO - mkdir: /builds/slave/test/build/upload/logs 22:21:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.472342 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-06-02 22:21:24.691419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:21:24.694825) ========= 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=aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3a6a383d5f2c4e7315987f37c6110153b8d42da3143f10129cbf47b9e0c3a22e4a1df039030d2ab252ca49ffa5070f9c09be1f32c169bbaff76bb7b0fdcb1ab7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27380f5955fa46da2681afa0f00baa69c7b4602afc3a72e3b770835a6365dd609c92b0e9965877c92f8ac04383a851a33af4bfcd92baf6ade4e1905324f7aefb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6e59d39efe612909bf960778179898f2211cdd56ebe22f9ec98823391ae99b5c6207cfe48e0b0c4042d70f5a888e96b19bbbf9f1771212ac6dc148983e937700"} build_url:https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011422 build_url: 'https://queue.taskcluster.net/v1/task/cN-KpsgvRQKyhxA3iZAElA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3a6a383d5f2c4e7315987f37c6110153b8d42da3143f10129cbf47b9e0c3a22e4a1df039030d2ab252ca49ffa5070f9c09be1f32c169bbaff76bb7b0fdcb1ab7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27380f5955fa46da2681afa0f00baa69c7b4602afc3a72e3b770835a6365dd609c92b0e9965877c92f8ac04383a851a33af4bfcd92baf6ade4e1905324f7aefb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6e59d39efe612909bf960778179898f2211cdd56ebe22f9ec98823391ae99b5c6207cfe48e0b0c4042d70f5a888e96b19bbbf9f1771212ac6dc148983e937700"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 22:21:24.731572) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:21:24.731868) ========= 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=aa08bb06ff6271ef470a76a4000001be-1464913935.824416-561503192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004642 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 22:21:24.781238) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:21:24.781543) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 22:21:24.782348) ========= ========= Total master_lag: 0.15 =========