builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1467036089.11 results: success (0) buildid: 20160627033730 builduid: 8b70a091270c461c9317c7abc0083234 revision: 20dfc217dbbca8148b7403b35459aa6be5dfc264 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.110425) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.110942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.111286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.150652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.151001) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 07:01:29-- 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% 224M=0s 2016-06-27 07:01:29 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082664 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.248418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.248797) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015165 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:29.298953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 07:01:29.299386) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20dfc217dbbca8148b7403b35459aa6be5dfc264 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20dfc217dbbca8148b7403b35459aa6be5dfc264 --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=c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 07:01:29,633 truncating revision to first 12 chars 2016-06-27 07:01:29,633 Setting DEBUG logging. 2016-06-27 07:01:29,633 attempt 1/10 2016-06-27 07:01:29,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/20dfc217dbbc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 07:01:31,026 unpacking tar archive at: mozilla-aurora-20dfc217dbbc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.726615 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 07:01:31.110336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:31.110703) ========= script_repo_revision: 20dfc217dbbca8148b7403b35459aa6be5dfc264 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:31.111327) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:31.111663) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 07:01:31.123087) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-27 07:01:31.123436) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:01:31 INFO - MultiFileLogger online at 20160627 07:01:31 in /builds/slave/test 07:01:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:01:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:01:31 INFO - {'append_to_log': False, 07:01:31 INFO - 'base_work_dir': '/builds/slave/test', 07:01:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:01:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:01:31 INFO - 'branch': 'Mozilla-Aurora', 07:01:31 INFO - 'buildbot_json_path': 'buildprops.json', 07:01:31 INFO - 'config_files': ('talos/linux_config.py',), 07:01:31 INFO - 'default_actions': ('clobber', 07:01:31 INFO - 'read-buildbot-config', 07:01:31 INFO - 'download-and-extract', 07:01:31 INFO - 'populate-webroot', 07:01:31 INFO - 'create-virtualenv', 07:01:31 INFO - 'install', 07:01:31 INFO - 'run-tests'), 07:01:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:01:31 INFO - 'download_minidump_stackwalk': True, 07:01:31 INFO - 'download_symbols': 'ondemand', 07:01:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:01:31 INFO - 'tooltool.py': '/tools/tooltool.py', 07:01:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:01:31 INFO - '/tools/misc-python/virtualenv.py')}, 07:01:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:01:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:01:31 INFO - 'installer_path': 'installer.exe', 07:01:31 INFO - 'log_level': 'info', 07:01:31 INFO - 'log_name': 'talos', 07:01:31 INFO - 'log_to_console': True, 07:01:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:01:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:01:31 INFO - 'opt_config_files': (), 07:01:31 INFO - 'pip_index': False, 07:01:31 INFO - 'sps_profile': False, 07:01:31 INFO - 'sps_profile_interval': 0, 07:01:31 INFO - 'suite': 'dromaeojs', 07:01:31 INFO - 'system_bits': '32', 07:01:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:01:31 INFO - 'title': 'talos-linux64-ix-004', 07:01:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:01:31 INFO - 'use_talos_json': True, 07:01:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:01:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:01:31 INFO - 'work_dir': 'build'} 07:01:31 INFO - ##### 07:01:31 INFO - ##### Running clobber step. 07:01:31 INFO - ##### 07:01:31 INFO - Running pre-action listener: _resource_record_pre_action 07:01:31 INFO - Running main action method: clobber 07:01:31 INFO - rmtree: /builds/slave/test/build 07:01:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:01:32 INFO - Running post-action listener: _resource_record_post_action 07:01:32 INFO - ##### 07:01:32 INFO - ##### Finished clobber step (success) 07:01:32 INFO - ##### 07:01:32 INFO - ##### 07:01:32 INFO - ##### Running read-buildbot-config step. 07:01:32 INFO - ##### 07:01:32 INFO - Running pre-action listener: _resource_record_pre_action 07:01:32 INFO - Running main action method: read_buildbot_config 07:01:32 INFO - Using buildbot properties: 07:01:32 INFO - { 07:01:32 INFO - "project": "", 07:01:32 INFO - "product": "firefox", 07:01:32 INFO - "script_repo_revision": "production", 07:01:32 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:01:32 INFO - "repository": "", 07:01:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:01:32 INFO - "buildid": "20160627033730", 07:01:32 INFO - "pgo_build": "True", 07:01:32 INFO - "basedir": "/builds/slave/test", 07:01:32 INFO - "buildnumber": 392, 07:01:32 INFO - "slavename": "talos-linux64-ix-004", 07:01:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:01:32 INFO - "platform": "ubuntu64_hw", 07:01:32 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:01:32 INFO - "branch": "mozilla-aurora", 07:01:32 INFO - "repo_path": "releases/mozilla-aurora", 07:01:32 INFO - "revision": "20dfc217dbbca8148b7403b35459aa6be5dfc264", 07:01:32 INFO - "stage_platform": "linux64-pgo", 07:01:32 INFO - "builduid": "8b70a091270c461c9317c7abc0083234", 07:01:32 INFO - "slavebuilddir": "test" 07:01:32 INFO - } 07:01:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 07:01:32 INFO - Running post-action listener: _resource_record_post_action 07:01:32 INFO - ##### 07:01:32 INFO - ##### Finished read-buildbot-config step (success) 07:01:32 INFO - ##### 07:01:32 INFO - ##### 07:01:32 INFO - ##### Running download-and-extract step. 07:01:32 INFO - ##### 07:01:32 INFO - Running pre-action listener: _resource_record_pre_action 07:01:32 INFO - Running main action method: download_and_extract 07:01:32 INFO - mkdir: /builds/slave/test/build/tests 07:01:32 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')]} 07:01:32 INFO - https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:01:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:01:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:01:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:01:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:01:33 INFO - Downloaded 1361 bytes. 07:01:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:01:33 INFO - Using the following test package requirements: 07:01:33 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 07:01:33 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:01:33 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:01:33 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'jsshell-linux-x86_64.zip'], 07:01:33 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:01:33 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 07:01:33 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:01:33 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:01:33 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:01:33 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:01:33 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:01:33 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:01:33 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')]} 07:01:33 INFO - https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:01:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:01:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:01:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:01:34 INFO - Downloaded 24517517 bytes. 07:01:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:01:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:01:36 INFO - Return code: 0 07:01:36 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:36 INFO - https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:01:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:01:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:01:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:01:38 INFO - Downloaded 24517517 bytes. 07:01:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:01:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:01:40 INFO - Return code: 0 07:01:40 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')]} 07:01:40 INFO - https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:01:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:01:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:01:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:01:41 INFO - Downloaded 10833369 bytes. 07:01:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:01:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:01:41 INFO - Return code: 0 07: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')]} 07:01:41 INFO - https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:01:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:01:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:01:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:01:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:01:42 INFO - Downloaded 63608155 bytes. 07:01:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:01:42 INFO - mkdir: /builds/slave/test/properties 07:01:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:01:42 INFO - Writing to file /builds/slave/test/properties/build_url 07:01:42 INFO - Contents: 07:01:42 INFO - build_url:https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:01:43 INFO - Running post-action listener: _resource_record_post_action 07:01:43 INFO - Running post-action listener: set_extra_try_arguments 07:01:43 INFO - ##### 07:01:43 INFO - ##### Finished download-and-extract step (success) 07:01:43 INFO - ##### 07:01:43 INFO - ##### 07:01:43 INFO - ##### Running populate-webroot step. 07:01:43 INFO - ##### 07:01:43 INFO - Running pre-action listener: _resource_record_pre_action 07:01:43 INFO - Running main action method: populate_webroot 07:01:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:01:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:01:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:01:43 INFO - '--tppagecycles', 07:01:43 INFO - '7'], 07:01:43 INFO - 'tests': ['tsvgm']}}, 07:01:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:01:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:01:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:01:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:01:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:01:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:01:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['damp', 'tps']}, 07:01:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'tests': ['damp', 'tps']}, 07:01:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['dromaeo_dom']}, 07:01:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:01:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['a11yr', 07:01:43 INFO - 'ts_paint', 07:01:43 INFO - 'tpaint', 07:01:43 INFO - 'sessionrestore', 07:01:43 INFO - 'sessionrestore_no_auto_restore', 07:01:43 INFO - 'tabpaint']}, 07:01:43 INFO - 'other-e10s': {'tests': ['a11yr', 07:01:43 INFO - 'ts_paint', 07:01:43 INFO - 'tpaint', 07:01:43 INFO - 'sessionrestore', 07:01:43 INFO - 'sessionrestore_no_auto_restore', 07:01:43 INFO - 'tabpaint']}, 07:01:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:01:43 INFO - 'ts_paint', 07:01:43 INFO - 'tpaint', 07:01:43 INFO - 'sessionrestore', 07:01:43 INFO - 'sessionrestore_no_auto_restore', 07:01:43 INFO - 'tabpaint']}, 07:01:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:01:43 INFO - 'ts_paint', 07:01:43 INFO - 'tpaint', 07:01:43 INFO - 'sessionrestore', 07:01:43 INFO - 'sessionrestore_no_auto_restore', 07:01:43 INFO - 'tabpaint']}, 07:01:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['a11yr', 07:01:43 INFO - 'ts_paint', 07:01:43 INFO - 'tpaint', 07:01:43 INFO - 'sessionrestore', 07:01:43 INFO - 'sessionrestore_no_auto_restore', 07:01:43 INFO - 'tabpaint']}, 07:01:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['a11yr', 07:01:43 INFO - 'ts_paint', 07:01:43 INFO - 'tpaint', 07:01:43 INFO - 'sessionrestore', 07:01:43 INFO - 'sessionrestore_no_auto_restore', 07:01:43 INFO - 'tabpaint']}, 07:01:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['tsvgx', 07:01:43 INFO - 'tsvgr_opacity', 07:01:43 INFO - 'tart', 07:01:43 INFO - 'tscrollx', 07:01:43 INFO - 'cart']}, 07:01:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:01:43 INFO - 'tsvgr_opacity', 07:01:43 INFO - 'tart', 07:01:43 INFO - 'tscrollx', 07:01:43 INFO - 'cart']}, 07:01:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'talos_options': ['--disable-e10s'], 07:01:43 INFO - 'tests': ['tp5o']}, 07:01:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'tests': ['tp5o']}, 07:01:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'talos_options': ['--disable-e10s', 07:01:43 INFO - '--xperf_path', 07:01:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:01:43 INFO - 'tests': ['tp5n']}, 07:01:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:43 INFO - 'talos_options': ['--xperf_path', 07:01:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:01:43 INFO - 'tests': ['tp5n']}}, 07:01:43 INFO - 'talos.zip': {'path': '', 07:01:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:01:43 INFO - Running post-action listener: _resource_record_post_action 07:01:43 INFO - ##### 07:01:43 INFO - ##### Finished populate-webroot step (success) 07:01:43 INFO - ##### 07:01:43 INFO - ##### 07:01:43 INFO - ##### Running create-virtualenv step. 07:01:43 INFO - ##### 07:01:43 INFO - Running pre-action listener: _resource_record_pre_action 07:01:43 INFO - Running main action method: create_virtualenv 07:01:43 INFO - Creating virtualenv /builds/slave/test/build/venv 07:01:43 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 07:01:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:01:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:01:43 INFO - Using real prefix '/usr' 07:01:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:01:44 INFO - Installing distribute.............................................................................................................................................................................................done. 07:01:46 INFO - Installing pip.................done. 07:01:46 INFO - Return code: 0 07:01:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:01:46 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')]} 07:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:46 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')]} 07:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:46 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:01:46 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 07:01:46 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 07:01:46 INFO - Using env: {'DISPLAY': ':0', 07:01:46 INFO - 'HOME': '/home/cltbld', 07:01:46 INFO - 'LANG': 'en_US.UTF-8', 07:01:46 INFO - 'LANGUAGE': 'en_US:en', 07:01:46 INFO - 'LOGNAME': 'cltbld', 07:01:46 INFO - 'MAIL': '/var/mail/cltbld', 07:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:46 INFO - 'MOZ_NO_REMOTE': '1', 07:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:46 INFO - 'NO_EM_RESTART': '1', 07:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:46 INFO - 'PWD': '/builds/slave/test', 07:01:46 INFO - 'SHELL': '/bin/bash', 07:01:46 INFO - 'SHLVL': '1', 07:01:46 INFO - 'TERM': 'linux', 07:01:46 INFO - 'TMOUT': '86400', 07:01:46 INFO - 'USER': 'cltbld', 07:01:46 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:46 INFO - '_': '/tools/buildbot/bin/python'} 07:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:46 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 07:01:46 INFO - Cleaning up... 07:01:46 INFO - Return code: 0 07:01:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:01:46 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')]} 07:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:46 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')]} 07:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:46 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:01:46 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 07:01:46 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 07:01:46 INFO - Using env: {'DISPLAY': ':0', 07:01:46 INFO - 'HOME': '/home/cltbld', 07:01:46 INFO - 'LANG': 'en_US.UTF-8', 07:01:46 INFO - 'LANGUAGE': 'en_US:en', 07:01:46 INFO - 'LOGNAME': 'cltbld', 07:01:46 INFO - 'MAIL': '/var/mail/cltbld', 07:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:46 INFO - 'MOZ_NO_REMOTE': '1', 07:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:46 INFO - 'NO_EM_RESTART': '1', 07:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:46 INFO - 'PWD': '/builds/slave/test', 07:01:46 INFO - 'SHELL': '/bin/bash', 07:01:46 INFO - 'SHLVL': '1', 07:01:46 INFO - 'TERM': 'linux', 07:01:46 INFO - 'TMOUT': '86400', 07:01:46 INFO - 'USER': 'cltbld', 07:01:46 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:46 INFO - '_': '/tools/buildbot/bin/python'} 07:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:46 INFO - Downloading/unpacking psutil>=3.1.1 07:01:46 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 07:01:46 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 07:01:46 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 07:01:46 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 07:01:46 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 07:01:46 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 07:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:01:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:01:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:01:48 INFO - Installing collected packages: psutil 07:01:48 INFO - Running setup.py install for psutil 07:01:48 INFO - building 'psutil._psutil_linux' extension 07:01:48 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 07:01:48 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 07:01:48 INFO - building 'psutil._psutil_posix' extension 07:01:48 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 07:01:49 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 07:01:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:01:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:01:49 INFO - Successfully installed psutil 07:01:49 INFO - Cleaning up... 07:01:49 INFO - Return code: 0 07:01:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:01:49 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')]} 07:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:49 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')]} 07:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:49 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:01:49 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 07:01:49 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 07:01:49 INFO - Using env: {'DISPLAY': ':0', 07:01:49 INFO - 'HOME': '/home/cltbld', 07:01:49 INFO - 'LANG': 'en_US.UTF-8', 07:01:49 INFO - 'LANGUAGE': 'en_US:en', 07:01:49 INFO - 'LOGNAME': 'cltbld', 07:01:49 INFO - 'MAIL': '/var/mail/cltbld', 07:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:49 INFO - 'MOZ_NO_REMOTE': '1', 07:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:49 INFO - 'NO_EM_RESTART': '1', 07:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:49 INFO - 'PWD': '/builds/slave/test', 07:01:49 INFO - 'SHELL': '/bin/bash', 07:01:49 INFO - 'SHLVL': '1', 07:01:49 INFO - 'TERM': 'linux', 07:01:49 INFO - 'TMOUT': '86400', 07:01:49 INFO - 'USER': 'cltbld', 07:01:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:49 INFO - '_': '/tools/buildbot/bin/python'} 07:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:01:51 INFO - Installing collected packages: mozsystemmonitor 07:01:51 INFO - Running setup.py install for mozsystemmonitor 07:01:51 INFO - Successfully installed mozsystemmonitor 07:01:51 INFO - Cleaning up... 07:01:51 INFO - Return code: 0 07:01:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:51 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:01:51 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 07:01:51 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 07:01:51 INFO - Using env: {'DISPLAY': ':0', 07:01:51 INFO - 'HOME': '/home/cltbld', 07:01:51 INFO - 'LANG': 'en_US.UTF-8', 07:01:51 INFO - 'LANGUAGE': 'en_US:en', 07:01:51 INFO - 'LOGNAME': 'cltbld', 07:01:51 INFO - 'MAIL': '/var/mail/cltbld', 07:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:51 INFO - 'MOZ_NO_REMOTE': '1', 07:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:51 INFO - 'NO_EM_RESTART': '1', 07:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:51 INFO - 'PWD': '/builds/slave/test', 07:01:51 INFO - 'SHELL': '/bin/bash', 07:01:51 INFO - 'SHLVL': '1', 07:01:51 INFO - 'TERM': 'linux', 07:01:51 INFO - 'TMOUT': '86400', 07:01:51 INFO - 'USER': 'cltbld', 07:01:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:51 INFO - '_': '/tools/buildbot/bin/python'} 07:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:51 INFO - Downloading/unpacking jsonschema==2.5.1 07:01:51 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 07:01:51 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 07:01:51 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 07:01:51 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 07:01:51 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 07:01:51 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 07:01:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:01:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:01:53 INFO - warning: no files found matching '*.txt' 07:01:53 INFO - no previously-included directories found matching 'build' 07:01:53 INFO - no previously-included directories found matching 'dist' 07:01:53 INFO - no previously-included directories found matching '.git*' 07:01:53 INFO - Installing collected packages: jsonschema, functools32 07:01:53 INFO - Running setup.py install for functools32 07:01:54 INFO - warning: no files found matching '*.txt' 07:01:54 INFO - no previously-included directories found matching 'build' 07:01:54 INFO - no previously-included directories found matching 'dist' 07:01:54 INFO - no previously-included directories found matching '.git*' 07:01:54 INFO - Successfully installed jsonschema functools32 07:01:54 INFO - Cleaning up... 07:01:54 INFO - Return code: 0 07:01:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:54 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:01:54 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 07:01:54 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 07:01:54 INFO - Using env: {'DISPLAY': ':0', 07:01:54 INFO - 'HOME': '/home/cltbld', 07:01:54 INFO - 'LANG': 'en_US.UTF-8', 07:01:54 INFO - 'LANGUAGE': 'en_US:en', 07:01:54 INFO - 'LOGNAME': 'cltbld', 07:01:54 INFO - 'MAIL': '/var/mail/cltbld', 07:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:54 INFO - 'MOZ_NO_REMOTE': '1', 07:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:54 INFO - 'NO_EM_RESTART': '1', 07:01:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:54 INFO - 'PWD': '/builds/slave/test', 07:01:54 INFO - 'SHELL': '/bin/bash', 07:01:54 INFO - 'SHLVL': '1', 07:01:54 INFO - 'TERM': 'linux', 07:01:54 INFO - 'TMOUT': '86400', 07:01:54 INFO - 'USER': 'cltbld', 07:01:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:54 INFO - '_': '/tools/buildbot/bin/python'} 07:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:54 INFO - Downloading/unpacking blobuploader==1.2.4 07:01:54 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 07:01:54 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 07:01:54 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 07:01:54 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 07:01:54 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 07:01:54 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 07:01:56 INFO - Downloading blobuploader-1.2.4.tar.gz 07:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:01:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:01:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 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 07:01:56 INFO - Downloading docopt-0.6.1.tar.gz 07:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:01:57 INFO - Installing collected packages: blobuploader, requests, docopt 07:01:57 INFO - Running setup.py install for blobuploader 07:01:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:01:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:01:57 INFO - Running setup.py install for requests 07:01:57 INFO - Running setup.py install for docopt 07:01:57 INFO - Successfully installed blobuploader requests docopt 07:01:57 INFO - Cleaning up... 07:01:57 INFO - Return code: 0 07:01:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:57 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:01:57 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 07:01:57 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 07:01:57 INFO - Using env: {'DISPLAY': ':0', 07:01:57 INFO - 'HOME': '/home/cltbld', 07:01:57 INFO - 'LANG': 'en_US.UTF-8', 07:01:57 INFO - 'LANGUAGE': 'en_US:en', 07:01:57 INFO - 'LOGNAME': 'cltbld', 07:01:57 INFO - 'MAIL': '/var/mail/cltbld', 07:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:57 INFO - 'MOZ_NO_REMOTE': '1', 07:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:57 INFO - 'NO_EM_RESTART': '1', 07:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:57 INFO - 'PWD': '/builds/slave/test', 07:01:57 INFO - 'SHELL': '/bin/bash', 07:01:57 INFO - 'SHLVL': '1', 07:01:57 INFO - 'TERM': 'linux', 07:01:57 INFO - 'TMOUT': '86400', 07:01:57 INFO - 'USER': 'cltbld', 07:01:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:57 INFO - '_': '/tools/buildbot/bin/python'} 07:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:01:57 INFO - Running setup.py (path:/tmp/pip-C9pnj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:01:57 INFO - Running setup.py (path:/tmp/pip-WNSno9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:01:58 INFO - Running setup.py (path:/tmp/pip-4Nw2f9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:01:58 INFO - Running setup.py (path:/tmp/pip-A6kvxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:01:58 INFO - Running setup.py (path:/tmp/pip-UUcPFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:01:58 INFO - Running setup.py (path:/tmp/pip-RZaImr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:01:58 INFO - Running setup.py (path:/tmp/pip-O93xKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:01:58 INFO - Running setup.py (path:/tmp/pip-ugTPwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:01:58 INFO - Running setup.py (path:/tmp/pip-_NV347-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:01:58 INFO - Running setup.py (path:/tmp/pip-mAd1yB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:01:58 INFO - Running setup.py (path:/tmp/pip-5U4gLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:01:58 INFO - Running setup.py (path:/tmp/pip-4qPJt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:01:58 INFO - Running setup.py (path:/tmp/pip-nOEYMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:01:58 INFO - Running setup.py (path:/tmp/pip-HctOu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:58 INFO - Running setup.py (path:/tmp/pip-GGkGX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:01:58 INFO - Running setup.py (path:/tmp/pip-MgutLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:01:59 INFO - Running setup.py (path:/tmp/pip-QkLqjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:01:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:01:59 INFO - Running setup.py install for manifestparser 07:01:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Running setup.py install for mozcrash 07:01:59 INFO - Running setup.py install for mozdebug 07:01:59 INFO - Running setup.py install for mozdevice 07:01:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Running setup.py install for mozfile 07:01:59 INFO - Running setup.py install for mozhttpd 07:01:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Running setup.py install for mozinfo 07:01:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Running setup.py install for mozInstall 07:01:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:01:59 INFO - Running setup.py install for mozleak 07:01:59 INFO - Running setup.py install for mozlog 07:02:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Running setup.py install for moznetwork 07:02:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Running setup.py install for mozprocess 07:02:00 INFO - Running setup.py install for mozprofile 07:02:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Running setup.py install for mozrunner 07:02:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Running setup.py install for mozscreenshot 07:02:00 INFO - Running setup.py install for moztest 07:02:00 INFO - Running setup.py install for mozversion 07:02:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:02:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:02:00 INFO - Cleaning up... 07:02:00 INFO - Return code: 0 07:02:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:00 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:02:00 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 07:02:00 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 07:02:00 INFO - Using env: {'DISPLAY': ':0', 07:02:00 INFO - 'HOME': '/home/cltbld', 07:02:00 INFO - 'LANG': 'en_US.UTF-8', 07:02:00 INFO - 'LANGUAGE': 'en_US:en', 07:02:00 INFO - 'LOGNAME': 'cltbld', 07:02:00 INFO - 'MAIL': '/var/mail/cltbld', 07:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:00 INFO - 'MOZ_NO_REMOTE': '1', 07:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:02:00 INFO - 'NO_EM_RESTART': '1', 07:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:00 INFO - 'PWD': '/builds/slave/test', 07:02:00 INFO - 'SHELL': '/bin/bash', 07:02:00 INFO - 'SHLVL': '1', 07:02:00 INFO - 'TERM': 'linux', 07:02:00 INFO - 'TMOUT': '86400', 07:02:00 INFO - 'USER': 'cltbld', 07:02:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:00 INFO - '_': '/tools/buildbot/bin/python'} 07:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:02:01 INFO - Running setup.py (path:/tmp/pip-YBRsl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:02:01 INFO - Running setup.py (path:/tmp/pip-R86oKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:02:01 INFO - Running setup.py (path:/tmp/pip-iszcn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:02:01 INFO - Running setup.py (path:/tmp/pip-C6J8Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:02:01 INFO - Running setup.py (path:/tmp/pip-w55h4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:02:01 INFO - Running setup.py (path:/tmp/pip-F5u_47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:01 INFO - Running setup.py (path:/tmp/pip-QUTKnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:01 INFO - Running setup.py (path:/tmp/pip-J66i_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:01 INFO - Running setup.py (path:/tmp/pip-xI3BEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:01 INFO - Running setup.py (path:/tmp/pip-q6lhoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:01 INFO - Running setup.py (path:/tmp/pip-zT0725-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:01 INFO - Running setup.py (path:/tmp/pip-eD0Y9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:01 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)) 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:02 INFO - Running setup.py (path:/tmp/pip-TblWOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:02 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)) 07:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:02 INFO - Running setup.py (path:/tmp/pip-Zi6ubq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:02 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)) 07:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:02 INFO - Running setup.py (path:/tmp/pip-lRzx1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:02 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)) 07:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:02 INFO - Running setup.py (path:/tmp/pip-YaiILw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:02 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)) 07:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:02 INFO - Running setup.py (path:/tmp/pip-HOt158-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:02 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)) 07:02:02 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)) 07:02:02 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)) 07:02:02 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)) 07:02:02 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)) 07:02:02 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)) 07:02:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:02:02 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 07:02:02 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 07:02:02 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 07:02:02 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 07:02:02 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 07:02:02 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 07:02:04 INFO - Downloading blessings-1.6.tar.gz 07:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:02:04 INFO - Installing collected packages: blessings 07:02:04 INFO - Running setup.py install for blessings 07:02:04 INFO - Successfully installed blessings 07:02:04 INFO - Cleaning up... 07:02:04 INFO - Return code: 0 07:02:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:02:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:02:04 INFO - Reading from file tmpfile_stdout 07:02:04 INFO - Current package versions: 07:02:04 INFO - argparse == 1.2.1 07:02:04 INFO - blessings == 1.6 07:02:04 INFO - blobuploader == 1.2.4 07:02:04 INFO - docopt == 0.6.1 07:02:04 INFO - functools32 == 3.2.3-2 07:02:04 INFO - jsonschema == 2.5.1 07:02:04 INFO - manifestparser == 1.1 07:02:04 INFO - mozInstall == 1.12 07:02:04 INFO - mozcrash == 0.17 07:02:04 INFO - mozdebug == 0.1 07:02:04 INFO - mozdevice == 0.48 07:02:04 INFO - mozfile == 1.2 07:02:04 INFO - mozhttpd == 0.7 07:02:04 INFO - mozinfo == 0.9 07:02:04 INFO - mozleak == 0.1 07:02:04 INFO - mozlog == 3.2 07:02:04 INFO - moznetwork == 0.27 07:02:04 INFO - mozprocess == 0.22 07:02:04 INFO - mozprofile == 0.28 07:02:04 INFO - mozrunner == 6.11 07:02:04 INFO - mozscreenshot == 0.1 07:02:04 INFO - mozsystemmonitor == 0.3 07:02:04 INFO - moztest == 0.7 07:02:04 INFO - mozversion == 1.4 07:02:04 INFO - psutil == 3.1.1 07:02:04 INFO - requests == 1.2.3 07:02:04 INFO - wsgiref == 0.1.2 07:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:04 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')]} 07:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:04 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')]} 07:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:04 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 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:02:04 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 07:02:04 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 07:02:04 INFO - Using env: {'DISPLAY': ':0', 07:02:04 INFO - 'HOME': '/home/cltbld', 07:02:04 INFO - 'LANG': 'en_US.UTF-8', 07:02:04 INFO - 'LANGUAGE': 'en_US:en', 07:02:04 INFO - 'LOGNAME': 'cltbld', 07:02:04 INFO - 'MAIL': '/var/mail/cltbld', 07:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:04 INFO - 'MOZ_NO_REMOTE': '1', 07:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:02:04 INFO - 'NO_EM_RESTART': '1', 07:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:04 INFO - 'PWD': '/builds/slave/test', 07:02:04 INFO - 'SHELL': '/bin/bash', 07:02:04 INFO - 'SHLVL': '1', 07:02:04 INFO - 'TERM': 'linux', 07:02:04 INFO - 'TMOUT': '86400', 07:02:04 INFO - 'USER': 'cltbld', 07:02:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:04 INFO - '_': '/tools/buildbot/bin/python'} 07:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 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)) 07:02:05 INFO - Cleaning up... 07:02:05 INFO - Return code: 0 07:02:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:02: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')]} 07:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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')]} 07:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:02: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', 'jsonschema'] in /builds/slave/test/build 07:02: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 jsonschema 07:02:05 INFO - Using env: {'DISPLAY': ':0', 07:02:05 INFO - 'HOME': '/home/cltbld', 07:02:05 INFO - 'LANG': 'en_US.UTF-8', 07:02:05 INFO - 'LANGUAGE': 'en_US:en', 07:02:05 INFO - 'LOGNAME': 'cltbld', 07:02:05 INFO - 'MAIL': '/var/mail/cltbld', 07:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:05 INFO - 'MOZ_NO_REMOTE': '1', 07:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:02:05 INFO - 'NO_EM_RESTART': '1', 07:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:05 INFO - 'PWD': '/builds/slave/test', 07:02:05 INFO - 'SHELL': '/bin/bash', 07:02:05 INFO - 'SHLVL': '1', 07:02:05 INFO - 'TERM': 'linux', 07:02:05 INFO - 'TMOUT': '86400', 07:02:05 INFO - 'USER': 'cltbld', 07:02:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:05 INFO - '_': '/tools/buildbot/bin/python'} 07:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:02:05 INFO - Cleaning up... 07:02:05 INFO - Return code: 0 07:02:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:02: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')]} 07:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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')]} 07:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a74ea6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ee350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, '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': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', '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 07:02: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', 'flake8'] in /builds/slave/test/build 07:02: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 flake8 07:02:05 INFO - Using env: {'DISPLAY': ':0', 07:02:05 INFO - 'HOME': '/home/cltbld', 07:02:05 INFO - 'LANG': 'en_US.UTF-8', 07:02:05 INFO - 'LANGUAGE': 'en_US:en', 07:02:05 INFO - 'LOGNAME': 'cltbld', 07:02:05 INFO - 'MAIL': '/var/mail/cltbld', 07:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:05 INFO - 'MOZ_NO_REMOTE': '1', 07:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:02:05 INFO - 'NO_EM_RESTART': '1', 07:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:05 INFO - 'PWD': '/builds/slave/test', 07:02:05 INFO - 'SHELL': '/bin/bash', 07:02:05 INFO - 'SHLVL': '1', 07:02:05 INFO - 'TERM': 'linux', 07:02:05 INFO - 'TMOUT': '86400', 07:02:05 INFO - 'USER': 'cltbld', 07:02:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:05 INFO - '_': '/tools/buildbot/bin/python'} 07:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:05 INFO - Downloading/unpacking flake8 07:02: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 07:02: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 07:02: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 07:02: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 07:02: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 07:02: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 07:02:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:02:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:02:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:07 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 07:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:02:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:02:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:02:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:02:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:02:08 INFO - no previously-included directories found matching 'docs/_build' 07:02:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:02:08 INFO - Running setup.py install for pep8 07:02:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:02:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:02:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:02:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:02:08 INFO - no previously-included directories found matching 'docs/_build' 07:02:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:02:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:02:08 INFO - Cleaning up... 07:02:08 INFO - Return code: 0 07:02:08 INFO - Running post-action listener: _resource_record_post_action 07:02:08 INFO - Running post-action listener: _start_resource_monitoring 07:02:08 INFO - Starting resource monitoring. 07:02:08 INFO - ##### 07:02:08 INFO - ##### Finished create-virtualenv step (success) 07:02:08 INFO - ##### 07:02:08 INFO - ##### 07:02:08 INFO - ##### Running install step. 07:02:08 INFO - ##### 07:02:08 INFO - Running pre-action listener: _resource_record_pre_action 07:02:08 INFO - Running main action method: install 07:02:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:02:08 INFO - Reading from file tmpfile_stdout 07:02:08 INFO - Detecting whether we're running mozinstall >=1.0... 07:02:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:02:08 INFO - Reading from file tmpfile_stdout 07:02:08 INFO - Output received: 07:02:08 INFO - Usage: mozinstall [options] installer 07:02:08 INFO - Options: 07:02:08 INFO - -h, --help show this help message and exit 07:02:08 INFO - -d DEST, --destination=DEST 07:02:08 INFO - Directory to install application into. [default: 07:02:08 INFO - "/builds/slave/test"] 07:02:08 INFO - --app=APP Application being installed. [default: firefox] 07:02:08 INFO - mkdir: /builds/slave/test/build/application 07:02:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:02:24 INFO - Reading from file tmpfile_stdout 07:02:24 INFO - Output received: 07:02:24 INFO - /builds/slave/test/build/application/firefox/firefox 07:02:24 INFO - Running post-action listener: _resource_record_post_action 07:02:24 INFO - ##### 07:02:24 INFO - ##### Finished install step (success) 07:02:24 INFO - ##### 07:02:24 INFO - ##### 07:02:24 INFO - ##### Running run-tests step. 07:02:24 INFO - ##### 07:02:24 INFO - Running pre-action listener: _resource_record_pre_action 07:02:24 INFO - Running main action method: run_tests 07:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:02:24 INFO - Python 2.7.3 07:02:24 INFO - Return code: 0 07:02:24 INFO - grabbing minidump binary from tooltool 07:02:24 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')]} 07:02:24 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 0x277d1c0>, '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 0x277e0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x277e550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:02:24 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 07:02:24 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 07:02:24 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 07:02:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:02:24 INFO - Return code: 0 07:02:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:02:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:02:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:02:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:02:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:02:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:02:25 INFO - Return code: 0 07:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 07:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 07:02:25 INFO - Using env: {'DISPLAY': ':0', 07:02:25 INFO - 'HOME': '/home/cltbld', 07:02:25 INFO - 'LANG': 'en_US.UTF-8', 07:02:25 INFO - 'LANGUAGE': 'en_US:en', 07:02:25 INFO - 'LOGNAME': 'cltbld', 07:02:25 INFO - 'MAIL': '/var/mail/cltbld', 07:02:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:02:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:25 INFO - 'MOZ_NO_REMOTE': '1', 07:02:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:02:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:02:25 INFO - 'NO_EM_RESTART': '1', 07:02:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:25 INFO - 'PWD': '/builds/slave/test', 07:02:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:02:25 INFO - 'SHELL': '/bin/bash', 07:02:25 INFO - 'SHLVL': '1', 07:02:25 INFO - 'TERM': 'linux', 07:02:25 INFO - 'TMOUT': '86400', 07:02:25 INFO - 'USER': 'cltbld', 07:02:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719', 07:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:25 INFO - '_': '/tools/buildbot/bin/python'} 07:02:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 07:02:25 INFO - mozversion application_buildid: 20160627033730 07:02:25 INFO - mozversion application_changeset: 20dfc217dbbca8148b7403b35459aa6be5dfc264 07:02:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:02:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:02:25 INFO - mozversion application_name: Firefox 07:02:25 INFO - mozversion application_remotingname: firefox-dev 07:02:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:02:25 INFO - mozversion application_vendor: Mozilla 07:02:25 INFO - mozversion application_version: 49.0a2 07:02:25 INFO - mozversion platform_buildid: 20160627033730 07:02:25 INFO - mozversion platform_changeset: 20dfc217dbbca8148b7403b35459aa6be5dfc264 07:02:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:02:25 INFO - mozversion platform_version: 49.0a2 07:02:25 INFO - using testdate: 1467036145 07:02:25 INFO - actual date: 1467036145 07:02:25 INFO - starting webserver on 'localhost:35899' 07:02:25 INFO - SUITE-START | Running 2 tests 07:02:25 INFO - TEST-START | dromaeo_css 07:02:25 INFO - operating with platform_type : linux_ 07:02:25 INFO - Initialising browser for dromaeo_css test... 07:02:25 INFO - TEST-INFO | started process 25422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoUkEBn/profile http://localhost:35899/getInfo.html) 07:02:29 INFO - PROCESS | 25422 | __metrics Screen width/height:1600/1200 07:02:29 INFO - PROCESS | 25422 | colorDepth:24 07:02:29 INFO - PROCESS | 25422 | Browser inner width/height: 1024/697 07:02:29 INFO - PROCESS | 25422 | __metrics 07:02:30 ERROR - PROCESS | 25422 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:02:31 INFO - PROCESS | 25422 | console.error: PushService: 07:02:31 INFO - PROCESS | 25422 | stateChangeProcessEnqueue: Error transitioning state 07:02:31 INFO - PROCESS | 25422 | UnknownError 07:02:31 INFO - TEST-INFO | 25422: exit 0 07:02:31 INFO - Browser initialized. 07:02:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:02:31 INFO - TEST-INFO | started process 25517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoUkEBn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:02:43 INFO - PROCESS | 25517 | RSS: Main: 145379328 07:02:43 INFO - PROCESS | 25517 | 07:03:35 INFO - PROCESS | 25517 | 1467036215296 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] 07:05:43 INFO - PROCESS | 25517 | Cycle 1(1): loaded http://localhost:35899/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35899/tests/dromaeo/cssquery-ext.html) 07:05:44 INFO - PROCESS | 25517 | RSS: Main: 256962560 07:05:44 INFO - PROCESS | 25517 | 07:08:47 INFO - PROCESS | 25517 | Cycle 1(1): loaded http://localhost:35899/tests/dromaeo/cssquery-ext.html (next: http://localhost:35899/tests/dromaeo/cssquery-jquery.html) 07:08:47 INFO - PROCESS | 25517 | RSS: Main: 229036032 07:08:47 INFO - PROCESS | 25517 | 07:11:49 INFO - PROCESS | 25517 | Cycle 1(1): loaded http://localhost:35899/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35899/tests/dromaeo/cssquery-mootools.html) 07:11:50 INFO - PROCESS | 25517 | RSS: Main: 224313344 07:11:50 INFO - PROCESS | 25517 | 07:14:52 INFO - PROCESS | 25517 | Cycle 1(1): loaded http://localhost:35899/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35899/tests/dromaeo/cssquery-prototype.html) 07:14:52 INFO - PROCESS | 25517 | RSS: Main: 226418688 07:14:52 INFO - PROCESS | 25517 | 07:17:55 INFO - PROCESS | 25517 | Cycle 1(1): loaded http://localhost:35899/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35899/tests/dromaeo/cssquery-yui.html) 07:17:55 INFO - PROCESS | 25517 | RSS: Main: 213688320 07:17:55 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | Cycle 1(1): loaded http://localhost:35899/tests/dromaeo/cssquery-yui.html (next: http://localhost:35899/tests/dromaeo/cssquery-dojo.html) 07:20:58 INFO - PROCESS | 25517 | RSS: Main: 251416576 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | __start_tp_report 07:20:58 INFO - PROCESS | 25517 | _x_x_mozilla_page_load 07:20:58 INFO - PROCESS | 25517 | _x_x_mozilla_page_load_details 07:20:58 INFO - PROCESS | 25517 | |i|pagename|runs| 07:20:58 INFO - PROCESS | 25517 | |0;dojo.html;3387.6612338766126;3412.334594754024;3417.350703366361;3447.1382154461385;3467.5838899332084;5626.212330273765;5638.154276858473;5697.515711164556;5701.885962280752;5719.628224165426;5667.688277144755;5720.914186287209;5721.799737009205;5725.14122881568;5730;5014.648974571782;5062.873428164288;5140.383154021513;5190.584753219752;5192.974035129819;5169.534741873231;5212.973935130319;5218.191180367049;5219.295395121648;5226.451222621627;5111.309973153632;5125.897482050356;5153.9742301288625;5175.327207463025;5193.58451323895;5244.8426547203835;5264.973675131638;5266.947330526684;5268.841934741964;5303.124984377573;4981.775820088069;5023.824166154167;5108.923366149511;5169.53474187325;5172.301739265226;5515.586331025189;5522.502974732293;5529.170624406331;5536.031194540939;5543.445655434464;6135.570510064297;6144.446999770043;6148.231471066117;6152.3847615238565;6161.784337548164;6045.72794224261;6050;6057.242844644419;6119.969400152971;6195.5663103582765;5711.857203569903;5735.053716136832;5767.509761670263;5768.278965129359;5768.509676677446;6052.213212282463;6266.436020758154;6280.905786413207;6282.780102696383;6284.717187726564;5939.168516407706;6062.575619706667;6064;6069.119977603311;6069.180660610849;6108.9733031877;6120.101005573244;6139.4001448443105;6140.565599444434;6144.279601711528;5923.763049477972;5980.252468441445;6121.540884433596;6125.754969801158;6144.016957286813;6031.9698401508585;6085.756569737249;6093.9390606093375;6094.725737341831;6133.785317513865;4609.446866375989;4614.238650622609;4622.46841613212;4623.514530974199;4652.860414187579;4088.652464540488;4127.7936103194725;4150.439690641785;4155.168966206771;4172.395002724648;5761.3950535194035;5783.074708046776;5784.363719990798;5786.971065144648;5811.564132690065;6164.568480206342;6200.193974783339;6206.844828879314;6209.720562574695;6217.564770466024;5624.690642014729;5752.194692743019;5793.073108302651;5797.391273916262;5843.923037087227;5325.440828713007;5331;5331.813386531453;5336.092864213093;5336.519713225828;6419.358064193543;6422.614643121335;6424.38968298011;6431.228252609717;6433.324500927335;5958.523318134452;6109.358517355791;6119.350229749377;6127.5866022601795;6235.594686345373;6397.936020639921;6400.135981642605;6430.807075787734;6439.034144878306;6448.967755161194;5897.5576831736935;6093.908591371044;6099.329073801967;6101.511879049576;6106.816795496142;6028.5177185825905;6057.061155520902;6079.118527813531;6131.386861313833;6142.201513803177;5967.671778052249;5969.313528944053;5977.402259773988;5981.192539007178;5981.461668449861;5847.473727364558;5856.824295271149;5888.116782482491;5982.982892908303;5985.191999080069;5809.82570522885;5914.7338369774325;5920.851978700567;5929.525637948867;5964.970175149097;6015.789447369321;6033.185519954925;6074.483668888194;6079.604825686316;6082.391760823883;5695.174199741097;5761.913571296518;5788.392218816964;5903.940960590511;5913.113033045078 07:20:58 INFO - PROCESS | 25517 | |1;ext.html;611.0925275965194;656.8653426047659;660.7456129390187;668.6623255256095;670.4200866250694;33343.166420839494;35839.82080089599;36403;45270.32094518577;46106.769466152706;47747.28379074308;48254.51745482544;48342.51657483424;48364;48392.75803620986;8986.730398088068;9129.543522823833;9157.725268241964;9164.72505824823;9172.908270917273;8407.411481196319;10611.151107911388;10888.183386246063;10894.727631809228;10914.508847101899;10601;10673.466326683634;10676.412797296145;11051.060659843923;11068.446577671084;8360;10692.679219623345;10908.50911708971;10991.780164396667;10993;10431;10740.409277489734;10753.892461075446;10771.892281077167;10774.245802793808;44921.101577968584;44995.325070123974;45076.09847803025;45246.77376613096;45337;3859.749116307459;3878.6703130233964;4247.150569886005;4394.868153955386;4448.1770872388715;30574.5235428786;31014;31230.84384578085;32173.1956701082;32548.511772323433;4634.490206077363;4731.716097034189;4736.265878788793;4787.6648634595595;4821.156297647897;380818;381982.09008955053;382274;382782;383490;38931.610683892795;39298.41052384216;70496;71305;71518.2848171522;41758.37362439566;41858;41906.37140442896;41933.1613367737;41937.58062419398;17925.28298868031;18101.7284740729;18111;18140.365087221882;18171.364002259856;13133.802992955114;14875.70248595022;15097.698046039237;15211.087334759728;15266.770998435033;9279.860802087975;9348.392354496937;9389.62441502346;9392.8591071134;9449.811003779885;3491.528643633128;3553.271579326232;3567.910802229965;3582.4089025311055;3591.533100696945;1012.7417508535424;1090.465671820802;1106.5794997900746;1175.2008634128874;1240.7368620528134;30805.537916931265;31080.533791993137;31211.531827022613;31484.21289467736;31674.841625792185;1161.6689243565543;1188.869224385317;1202.7233736240714;1239.8264243005985;1254.667513109027;16841.41055063067;16873.57816054584;17049.573760655836;17166;17326.653466930842;631406;635592;635694;636969;641706;64217.678911605144;64776;64987.35012649908;64999;65047.34952650507;1311.2132720367895;1406.528812847687;1419.7160567886258;1450.376338174595;1462.5027490653229;34006.3198736019;35670;36068.45897311595;36104.458433123;36394.45408318824;53807.73096134494;54497.72751136219;54520;54583;54669.453305466435;442997;443379;443987;445037;508379;31204.37591248117;31694.841525792224;31983.36033279368;33096.1725956853;33106.33787324288;53519.19721204263;53752.73123634357;54093;54862;55503;44471.55528444674;46017.22601722575;46319;46588.30117548307;47095.52904470912;45149;45290;45790.542094578625;46048.76975615101;47593.52406476029;44749.32876006928;45325.773371132935;45515.772421137684;45718.54281457276;46047.539524605665;38663.806680966416;39792.602073980044;40713;41113.79443102765;41540.96147596334;29567.26081847971;30331.241718957204;30416;30625.540616890317;30792.53811192789 07:20:58 INFO - PROCESS | 25517 | |2;jquery.html;432.9415528903598;435.23512158198514;439.60216004515917;440.9250427427337;442.88263610143315;18705;19099.52251193719;20159.496012599673;20247.088881000353;20293.28973485928;18891.90554047228;19839.702404463944;19854.205831766747;19988.90005549974;20472.385828425133;5142.460041695625;5337.412884582693;5372.623916325859;5379.6503227290295;5382.6770393776305;5233.555147812441;5327;5372.247885296062;5567.49892509674;5845.970770146143;5699.059655156894;5783.681897495636;5802.535797136219;5825.213596164527;5831.737571809279;5336.866578335534;5338.492843179931;5473.17902314652;5715.3141623005295;5808.796692115777;5091.745412729386;5209.739513024371;5217.321748172713;5220.686758794484;5243.632945693803;14412.207328596924;15149.697006059927;22231.555368892692;23358.065677373055;23430.297091087294;2271.216430331533;2302.919397821085;2329.219711396684;2348.8708121053337;2829.7311755383207;10388;10620.097291730212;10867.728306792314;10887.782244355069;13646.590602281947;1801.2704854533936;1802.1766806857997;1822.56928311275;1881.5766452548205;1980.6632872411724;86457;88931.55534222352;89975.10024899733;89986.10013899843;90393;11527.596534121432;11675.299482031105;11796.646100616996;11944;12021.819672704916;16722.24749886258;16794.83205167933;16803.831961680226;16818;16821.327146913987;8879.911200888038;9147;9208.677696280598;9293.349465537347;9322.550656257092;9099.272058235458;9173.770655733526;9188.540572971324;9445.291603129794;9500.714978550655;4941.2835138905375;4999.075171093359;5073.162928116893;5077.543021128116;5091.592672586185;2034.2574960139443;2056.969145462819;2077.761057478379;2101.9175124810736;2147.7422709274674;831.4512421801703;975.7072878136672;1062.654637242899;1087.7063192937862;1100.5047728522197;18515.81484185168;18615.696901217056;19576.412707618794;20105.597888042157;21272.149114035576;1025.707673313102;1029.8764148302103;1035.4667346316653;1056.984145237822;1092.8579284693099;10811.297265677706;11349.035331996707;11349.716257093638;11380.658580242605;11397.08823294151;500902;505463;511769;515876;522137;71155.28844711487;83737.58131209305;84113.15886841176;98532.50733746428;101650.49174754079;501.52551498594016;520.2794130129722;1066.6906597086759;1092.1317552545745;1105.5080489182237;11117;11289.887101129212;11732.648020559396;11886.465109069939;11994.220375675553;39993.60006399899;40899.38650920298;40965.287803027044;40988.79505602453;41605.58394416017;59299.40700592939;59681.701591491765;59787.701061494416;60288.69855650693;60765.69617151886;8372.455790373606;8588.65645374178;8633.955033979015;8794.164554367326;8794.384393092421;9398.389104708172;12975.351232438152;13548.525801596898;13769.03616746818;14057.086289391156;10914.280581642633;12149.69625759363;12240.75518489643;12333.691657708629;12483.500659973499;8769.561521924006;8778.91221087781;8966.68616598416;11994.76010479803;12304.138710290194;8670.393072485118;8924.152205540722;8951.194392504705;9074.45553266806;9271.397359171633;13019.609411717664;13415.26216058087;13535.59393218205;13625.65935851572;13763.724725505233;12098.274103553815;12186.2078964867;12414.565490207799;12416.255024698547;12769.744605108031 07:20:58 INFO - PROCESS | 25517 | |3;mootools.html;1425.8360288566819;1550.5735922621277;1606.4859245041587;1806.223323970693;1938.2053358123173;9446.905530944689;9787.363821351624;9788.510574471284;9800.019998000196;9808.754781130476;9487.667931622394;9691.903080969189;9723.173530249936;9729.854052189223;9736.756581085476;2206.7462241842154;2473.2332976777257;2509.82431229814;2525.4570267392505;2537.1627362970266;2558.09187738353;2564.781993530543;2566.0633868637906;2567.6918769747563;2569.871506424681;2527.810414218932;2548.4011257354505;2550.2859199424097;2551.9489610207784;2552.6553915221484;2145.8605190662556;2410.4576470294132;2503.824732268742;2503.9374015649573;2526.823122381434;2398.4963157736897;2414.7585241475886;2426.1751004658327;2469.802415806731;2474.9628755568683;9541.666041688508;9575;9598.808023839483;9603.855942160873;9610.135087842127;5760.8271751847515;5878.441548052928;5880.26496687914;5912.822615321547;5916.083007133858;3602.963970360315;3625.963740362589;3695.946655203254;3731.925361492782;3765.2281282337053;1216.8113942338953;1229.7970834812245;1264.3488603369271;1333.593254057512;1345.8855997240246;14107.506237281748;14139.222342771143;14157.433702651882;14158.150510969272;14158.929205353907;5121.795128194831;5156.690598564098;5172.250023746536;5180.197069454241;5256.553192978639;11425.714357140972;11560.479778409992;11563.42182890852;11580.826287605692;11595.420228988685;5057.190849464068;5582.939241544093;5658.91511627326;5667.064934285879;5667.234923285304;4637.211674015425;4723.811047558059;4769.2130798417875;5440.700761458079;5447.918281225785;4383;4389.868303950887;4494.910101798011;4548;4557.361969324297;1393.5540626999466;1424.1312799192485;1427.2863568215892;1429.6783223774567;1444.3572610188364;1002.8395456726888;1013.7212266626589;1020.5764607687727;1024.6618615856748;1024.933379330341;6372.617642941438;6471.320511346335;6506.284308726036;6659.500537459709;6701.530892837454;498.88026873550086;512.4542362384049;513.1840373805593;519.8258583374536;520.2014907117632;4536.4102666653125;4552.317152427162;4564.7032942858605;4610.331501932268;4619.7757594237555;11659;11904.345261010727;12410.069244806675;12417.19288246261;12443.191192572454;12078.637640870787;12326.81509777354;12456.688582785502;12463.376831158406;12473.563425280072;8971.05803890595;9143.268538516902;9153.908460915305;9174.403663761834;9176.31177661678;6539.378759017885;6762.289959554176;6762.830929226809;6866;6868.896966545406;11169.162312826407;11358.659240222476;11491.597794077166;11689.357085360387;11722.70693232676;11485.885141148481;11689.415529223335;11726.706832328919;11890.405479725807;11896.167268291136;7776.805579860323;8090.83818323642;8277.83444331122;8342.499450033018;8584.785380365542;9476.384035037701;9476.52617369115;9553.474558899326;9558.330916835755;9569.952150239204;8421;8557.743267701979;8561.272291855263;8571.271441927505;8666.523341216294;8421.915780842113;8460.153984601491;8464;8487.957560212159;8574.3997920145;7350.338469537767;7416.110066792019;7417.962910185414;7425.888611670937;7429.108506979197;5370.919436208538;5441.319835020622;5506.449355064461;5563.527100196528;5698.715064246854;5540.1135818270495;5566.359868614999;5605.747741351731;5638.323401191883;5679.233303503974 07:20:58 INFO - PROCESS | 25517 | |4;prototype.html;340.6287147009759;345.2111924253081;350.224253278987;350.73344258363636;357.19809028730504;5487.478689524493;5529.894021195757;5548.472895074967;5557.138643510258;5558.638688485245;5473.808416705416;5490.478404551562;5490.588205884555;5511.200875872996;5519.503244707976;4162.666986641061;4340.283853164224;4342.435483387171;4343.739375637456;4346.97826510867;4289.4209281747035;4316.892077698051;4327.177836211125;4329;4332.28517294646;4047.919041619166;4308.224519586473;4315.50371707253;4318.287482565373;4326.351047342908;4152.418661387408;4291.506476755167;4294.9785251073545;4300.161468513641;4311.805968731414;3776.3580191367532;3800.7339486235974;3807.390817469219;3819.217060502618;3836.9040773980596;5060.822871199491;5167.940572182693;5260.89607131794;5310.389305229871;5329.333833270841;4645.465771436295;4949.455559888409;4972.5524702776565;4978.7844409237905;4979.850604481871;5751.741171647244;5773.364929857712;5776.277965254343;5779.971100144515;5794.246747922785;4177.97911010446;4241.130568233536;4253.574642535722;4259.61663450291;4279.786010699484;5965.164876917235;6002.249718785152;6018.8495287617725;6025.367336429656;6038.33578306386;4341.3487976803735;4362;4362.323839804835;4362.738235705868;4366.563343665608;4316.654667626645;4365.978170109129;4385.6710746694125;4387.670924680661;4414.139242847614;4878.731669758126;4931.3589233399425;4947.480514545993;4956.90086198274;4960.553550180501;5780.583756979483;5811.593188476851;5823.854403639943;5824.417558244227;5834.212381328552;1479.634166098024;1496.895217334798;1523.2612183091192;1526.8015158029607;1530.6785575029146;964.62379671928;971.2618410008445;972.4311277902365;984.8821587498242;1001.2940876682068;4981.551660350586;4992.301077849104;5030.874228144326;5065.898682026413;5066.949330506722;5245.475452454724;5311.229871668586;5314.4951229633125;5370.610078515023;5372.247885296062;5087.669301495391;5479.917801232985;5488.9725551372785;5593.496585307342;5686.3744988051285;3154.747620190379;3154.921126971844;3157.163351711798;3170.9682903171133;3184.028871194308;5949.345571987078;6139.956207444745;6145.754169833246;6152.78465253714;6243.906341404882;6093.573449858467;6097.786577469856;6099.390060993954;6100.267967843887;6128.445501609056;6185.597936134137;6192.133101365723;6227.19046523949;6263.96868015657;6264.874702505925;5681;5997.820065398045;6046.697665116639;6048.274207095177;6053.9091913622215;5800.8826778226485;5929.703514824328;5979.970100149471;6009.617787908718;6011.6693581853415;5926.1925112841;6225.937740622536;6290.528210384331;6352.872942541031;6368.42684158428;4302.892427689208;4315.748432954408;4332.566743325642;4349.3475978603465;4356.716813407118;6163.568550201443;6178.505719542516;6180.81457556274;6182.196314479089;6195.721192546436;5705.030144875464;5949.8215053548465;5961.523078153824;5962.970185149046;5967.433093856077;5914.467697907209;5928;5934.97032514852;5937.435943585352;5968.492678122235;5648.943510564842;5829.464183080374;5849.181114643869;5859.5019423349495;5883.705814709333;5842.298924129132;5884.705764711662;5905.881882362242;5907.223604905687;5991.310999235144;5449.891002179855;5783.566232532493;5791.407362975216;5825.883482330414;5832.445917637818 07:20:58 INFO - PROCESS | 25517 | |5;yui.html;773.4353921637205;820.81941972766;871.3421366868015;874.3398733955862;932.7775657578445;15763.21183940804;15956.521304360873;15973.760393594106;15982.200889955498;16879.662406751857;16322.673546529062;16840.915795421035;16849.578760530992;16889.324427022904;16897.83102168981;870.4864130163203;870.495112834556;874.3442418186361;874.4884242718017;874.903760586335;738.4830618567024;863.7365603490932;886.5301390263171;891.6522556203054;893.63361021981;916.9128932751378;917.3303488453433;918.9770255743593;920.1626519866923;920.1626519866923;765.9846803063963;910.3172620534599;912.2884150362695;912.4479689787682;912.6623149434686;666.2371584535728;676.577139287945;679.5447050476192;680.9829754256183;681.6114814555705;15347.4628388007;15383.692326153414;15429.459968901034;15436.614084647861;15443.150626715526;14342.92828535861;14888.330025148894;14896.776548351783;14915.403383864632;14935.327910244065;13812.861871381258;13880.583582492542;13898.330463427395;14129.010969232158;14405.495807646683;4655.37152484417;4685.086408150412;4722.574968252874;4735.266033764772;4808.230683090724;77697.61151194264;79253.20746792515;79347.60326198333;79581.60209198974;79703.20296797073;11387.202895797214;12173.452194651261;12298;12515.937420312841;13000.609981700563;21566.137354505958;21984.670229946245;21999;21999.560008799734;22000.229991950204;9245.768855778659;9314.86027709585;9461.858072128924;9523;9558.116719024105;5061.44324124346;5101.770420331094;5118.564921981673;5161.896762064737;5165.74171291434;3871.7483363581277;4095.7542547447247;4100.897477563085;4123.670106391482;4134.73124246929;675.6520391998125;686.3034020469228;692.1624833950875;693.6947742993067;697.4908316928569;766.0768773627765;792.8731402975496;808.7174989222018;811.6996711216769;825.1707034430359;10933.781324373627;11069.833952490719;11117.388543630179;11332;11347.262427942158;785.5443842571409;808.6603626476893;814.2518998380466;819.7212947597845;840.0843081041572;4640.32715256286;4704.458987216445;4747.905041899212;4791.496892826272;4807.062622788592;120665;123628.38185809013;124782.37608811898;130250.34874825754;132913;107140;109864.94217137172;110000;110161;111413;60503.69748151319;61490.999999999105;62120;62187;62302.37697622966;9390;9616.278779091455;9669.9033009669;9673.661421850202;9939.353941993748;43038.78480607577;44217.55782442135;52007;52357;59914;50810.23784643159;50945.23582146345;51183.74408127936;52479.21281180709;73334.63332683516;12752.936235318764;12815.06225326462;12915.806262906252;13041.152325098838;13068.215907045607;14184.277092835464;15332.310046048178;15921.840781592036;16304.836951630807;16325.183740813149;10442.00800923911;12262.816057759319;14870.851291486946;14893.47872824446;15197.468088616846;12646.177998430441;13621.523246686318;13788.172709637454;14491.637709056937;14635;14951.626209344853;15065.246737662854;15231.847681523042;15238.238088095773;16054.678906421572;11019.228653994143;11092.445377730919;11107.72230694239;11168.497417616065;11750.353730544903;11527.250728702607;12322.507099715911;12381.018550684246;12492.125551211328;12609.558665446666 07:20:58 INFO - PROCESS | 25517 | __end_tp_report 07:20:58 INFO - PROCESS | 25517 | __start_cc_report 07:20:58 INFO - PROCESS | 25517 | _x_x_mozilla_cycle_collect,940 07:20:58 INFO - PROCESS | 25517 | __end_cc_report 07:20:58 INFO - PROCESS | 25517 | __startTimestamp1467037258542__endTimestamp 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | ------- Summary: start ------- 07:20:58 INFO - PROCESS | 25517 | Number of tests: 6 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | [#0] dojo.html Cycles:165 Average:5677.21 Median:5897.56 stddev:646.51 (11.0%) stddev-sans-first:623.04 07:20:58 INFO - PROCESS | 25517 | Values: 3387.7 3412.3 3417.4 3447.1 3467.6 5626.2 5638.2 5697.5 5701.9 5719.6 5667.7 5720.9 5721.8 5725.1 5730.0 5014.6 5062.9 5140.4 5190.6 5193.0 5169.5 5213.0 5218.2 5219.3 5226.5 5111.3 5125.9 5154.0 5175.3 5193.6 5244.8 5265.0 5266.9 5268.8 5303.1 4981.8 5023.8 5108.9 5169.5 5172.3 5515.6 5522.5 5529.2 5536.0 5543.4 6135.6 6144.4 6148.2 6152.4 6161.8 6045.7 6050.0 6057.2 6120.0 6195.6 5711.9 5735.1 5767.5 5768.3 5768.5 6052.2 6266.4 6280.9 6282.8 6284.7 5939.2 6062.6 6064.0 6069.1 6069.2 6109.0 6120.1 6139.4 6140.6 6144.3 5923.8 5980.3 6121.5 6125.8 6144.0 6032.0 6085.8 6093.9 6094.7 6133.8 4609.4 4614.2 4622.5 4623.5 4652.9 4088.7 4127.8 4150.4 4155.2 4172.4 5761.4 5783.1 5784.4 5787.0 5811.6 6164.6 6200.2 6206.8 6209.7 6217.6 5624.7 5752.2 5793.1 5797.4 5843.9 5325.4 5331.0 5331.8 5336.1 5336.5 6419.4 6422.6 6424.4 6431.2 6433.3 5958.5 6109.4 6119.4 6127.6 6235.6 6397.9 6400.1 6430.8 6439.0 6449.0 5897.6 6093.9 6099.3 6101.5 6106.8 6028.5 6057.1 6079.1 6131.4 6142.2 5967.7 5969.3 5977.4 5981.2 5981.5 5847.5 5856.8 5888.1 5983.0 5985.2 5809.8 5914.7 5920.9 5929.5 5965.0 6015.8 6033.2 6074.5 6079.6 6082.4 5695.2 5761.9 5788.4 5903.9 5913.1 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | [#1] ext.html Cycles:180 Average:65225.56 Median:39545.51 stddev:134041.92 (339.0%) stddev-sans-first:134330.15 07:20:58 INFO - PROCESS | 25517 | Values: 611.1 656.9 660.7 668.7 670.4 33343.2 35839.8 36403.0 45270.3 46106.8 47747.3 48254.5 48342.5 48364.0 48392.8 8986.7 9129.5 9157.7 9164.7 9172.9 8407.4 10611.2 10888.2 10894.7 10914.5 10601.0 10673.5 10676.4 11051.1 11068.4 8360.0 10692.7 10908.5 10991.8 10993.0 10431.0 10740.4 10753.9 10771.9 10774.2 44921.1 44995.3 45076.1 45246.8 45337.0 3859.7 3878.7 4247.2 4394.9 4448.2 30574.5 31014.0 31230.8 32173.2 32548.5 4634.5 4731.7 4736.3 4787.7 4821.2 380818.0 381982.1 382274.0 382782.0 383490.0 38931.6 39298.4 70496.0 71305.0 71518.3 41758.4 41858.0 41906.4 41933.2 41937.6 17925.3 18101.7 18111.0 18140.4 18171.4 13133.8 14875.7 15097.7 15211.1 15266.8 9279.9 9348.4 9389.6 9392.9 9449.8 3491.5 3553.3 3567.9 3582.4 3591.5 1012.7 1090.5 1106.6 1175.2 1240.7 30805.5 31080.5 31211.5 31484.2 31674.8 1161.7 1188.9 1202.7 1239.8 1254.7 16841.4 16873.6 17049.6 17166.0 17326.7 631406.0 635592.0 635694.0 636969.0 641706.0 64217.7 64776.0 64987.4 64999.0 65047.3 1311.2 1406.5 1419.7 1450.4 1462.5 34006.3 35670.0 36068.5 36104.5 36394.5 53807.7 54497.7 54520.0 54583.0 54669.5 442997.0 443379.0 443987.0 445037.0 508379.0 31204.4 31694.8 31983.4 33096.2 33106.3 53519.2 53752.7 54093.0 54862.0 55503.0 44471.6 46017.2 46319.0 46588.3 47095.5 45149.0 45290.0 45790.5 46048.8 47593.5 44749.3 45325.8 45515.8 45718.5 46047.5 38663.8 39792.6 40713.0 41113.8 41541.0 29567.3 30331.2 30416.0 30625.5 30792.5 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | [#2] jquery.html Cycles:180 Average:29709.93 Median:12889.58 stddev:84288.57 (653.9%) stddev-sans-first:84496.35 07:20:58 INFO - PROCESS | 25517 | Values: 432.9 435.2 439.6 440.9 442.9 18705.0 19099.5 20159.5 20247.1 20293.3 18891.9 19839.7 19854.2 19988.9 20472.4 5142.5 5337.4 5372.6 5379.7 5382.7 5233.6 5327.0 5372.2 5567.5 5846.0 5699.1 5783.7 5802.5 5825.2 5831.7 5336.9 5338.5 5473.2 5715.3 5808.8 5091.7 5209.7 5217.3 5220.7 5243.6 14412.2 15149.7 22231.6 23358.1 23430.3 2271.2 2302.9 2329.2 2348.9 2829.7 10388.0 10620.1 10867.7 10887.8 13646.6 1801.3 1802.2 1822.6 1881.6 1980.7 86457.0 88931.6 89975.1 89986.1 90393.0 11527.6 11675.3 11796.6 11944.0 12021.8 16722.2 16794.8 16803.8 16818.0 16821.3 8879.9 9147.0 9208.7 9293.3 9322.6 9099.3 9173.8 9188.5 9445.3 9500.7 4941.3 4999.1 5073.2 5077.5 5091.6 2034.3 2057.0 2077.8 2101.9 2147.7 831.5 975.7 1062.7 1087.7 1100.5 18515.8 18615.7 19576.4 20105.6 21272.1 1025.7 1029.9 1035.5 1057.0 1092.9 10811.3 11349.0 11349.7 11380.7 11397.1 500902.0 505463.0 511769.0 515876.0 522137.0 71155.3 83737.6 84113.2 98532.5 101650.5 501.5 520.3 1066.7 1092.1 1105.5 11117.0 11289.9 11732.6 11886.5 11994.2 39993.6 40899.4 40965.3 40988.8 41605.6 59299.4 59681.7 59787.7 60288.7 60765.7 8372.5 8588.7 8634.0 8794.2 8794.4 9398.4 12975.4 13548.5 13769.0 14057.1 10914.3 12149.7 12240.8 12333.7 12483.5 8769.6 8778.9 8966.7 11994.8 12304.1 8670.4 8924.2 8951.2 9074.5 9271.4 13019.6 13415.3 13535.6 13625.7 13763.7 12098.3 12186.2 12414.6 12416.3 12769.7 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | [#3] mootools.html Cycles:180 Average:6410.80 Median:4680.51 stddev:3762.68 (80.4%) stddev-sans-first:3754.59 07:20:58 INFO - PROCESS | 25517 | Values: 1425.8 1550.6 1606.5 1806.2 1938.2 9446.9 9787.4 9788.5 9800.0 9808.8 9487.7 9691.9 9723.2 9729.9 9736.8 2206.7 2473.2 2509.8 2525.5 2537.2 2558.1 2564.8 2566.1 2567.7 2569.9 2527.8 2548.4 2550.3 2551.9 2552.7 2145.9 2410.5 2503.8 2503.9 2526.8 2398.5 2414.8 2426.2 2469.8 2475.0 9541.7 9575.0 9598.8 9603.9 9610.1 5760.8 5878.4 5880.3 5912.8 5916.1 3603.0 3626.0 3695.9 3731.9 3765.2 1216.8 1229.8 1264.3 1333.6 1345.9 14107.5 14139.2 14157.4 14158.2 14158.9 5121.8 5156.7 5172.3 5180.2 5256.6 11425.7 11560.5 11563.4 11580.8 11595.4 5057.2 5582.9 5658.9 5667.1 5667.2 4637.2 4723.8 4769.2 5440.7 5447.9 4383.0 4389.9 4494.9 4548.0 4557.4 1393.6 1424.1 1427.3 1429.7 1444.4 1002.8 1013.7 1020.6 1024.7 1024.9 6372.6 6471.3 6506.3 6659.5 6701.5 498.9 512.5 513.2 519.8 520.2 4536.4 4552.3 4564.7 4610.3 4619.8 11659.0 11904.3 12410.1 12417.2 12443.2 12078.6 12326.8 12456.7 12463.4 12473.6 8971.1 9143.3 9153.9 9174.4 9176.3 6539.4 6762.3 6762.8 6866.0 6868.9 11169.2 11358.7 11491.6 11689.4 11722.7 11485.9 11689.4 11726.7 11890.4 11896.2 7776.8 8090.8 8277.8 8342.5 8584.8 9476.4 9476.5 9553.5 9558.3 9570.0 8421.0 8557.7 8561.3 8571.3 8666.5 8421.9 8460.2 8464.0 8488.0 8574.4 7350.3 7416.1 7418.0 7425.9 7429.1 5370.9 5441.3 5506.4 5563.5 5698.7 5540.1 5566.4 5605.7 5638.3 5679.2 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | [#4] prototype.html Cycles:180 Average:4885.56 Median:5488.23 stddev:1437.82 (26.2%) stddev-sans-first:1400.80 07:20:58 INFO - PROCESS | 25517 | Values: 340.6 345.2 350.2 350.7 357.2 5487.5 5529.9 5548.5 5557.1 5558.6 5473.8 5490.5 5490.6 5511.2 5519.5 4162.7 4340.3 4342.4 4343.7 4347.0 4289.4 4316.9 4327.2 4329.0 4332.3 4047.9 4308.2 4315.5 4318.3 4326.4 4152.4 4291.5 4295.0 4300.2 4311.8 3776.4 3800.7 3807.4 3819.2 3836.9 5060.8 5167.9 5260.9 5310.4 5329.3 4645.5 4949.5 4972.6 4978.8 4979.9 5751.7 5773.4 5776.3 5780.0 5794.2 4178.0 4241.1 4253.6 4259.6 4279.8 5965.2 6002.2 6018.8 6025.4 6038.3 4341.3 4362.0 4362.3 4362.7 4366.6 4316.7 4366.0 4385.7 4387.7 4414.1 4878.7 4931.4 4947.5 4956.9 4960.6 5780.6 5811.6 5823.9 5824.4 5834.2 1479.6 1496.9 1523.3 1526.8 1530.7 964.6 971.3 972.4 984.9 1001.3 4981.6 4992.3 5030.9 5065.9 5066.9 5245.5 5311.2 5314.5 5370.6 5372.2 5087.7 5479.9 5489.0 5593.5 5686.4 3154.7 3154.9 3157.2 3171.0 3184.0 5949.3 6140.0 6145.8 6152.8 6243.9 6093.6 6097.8 6099.4 6100.3 6128.4 6185.6 6192.1 6227.2 6264.0 6264.9 5681.0 5997.8 6046.7 6048.3 6053.9 5800.9 5929.7 5980.0 6009.6 6011.7 5926.2 6225.9 6290.5 6352.9 6368.4 4302.9 4315.7 4332.6 4349.3 4356.7 6163.6 6178.5 6180.8 6182.2 6195.7 5705.0 5949.8 5961.5 5963.0 5967.4 5914.5 5928.0 5935.0 5937.4 5968.5 5648.9 5829.5 5849.2 5859.5 5883.7 5842.3 5884.7 5905.9 5907.2 5991.3 5449.9 5783.6 5791.4 5825.9 5832.4 07:20:58 INFO - PROCESS | 25517 | 07:20:58 INFO - PROCESS | 25517 | [#5] yui.html Cycles:180 Average:20702.73 Median:43628.17 stddev:29883.17 (68.5%) stddev-sans-first:29929.53 07:20:58 INFO - PROCESS | 25517 | Values: 773.4 820.8 871.3 874.3 932.8 15763.2 15956.5 15973.8 15982.2 16879.7 16322.7 16840.9 16849.6 16889.3 16897.8 870.5 870.5 874.3 874.5 874.9 738.5 863.7 886.5 891.7 893.6 916.9 917.3 919.0 920.2 920.2 766.0 910.3 912.3 912.4 912.7 666.2 676.6 679.5 681.0 681.6 15347.5 15383.7 15429.5 15436.6 15443.2 14342.9 14888.3 14896.8 14915.4 14935.3 13812.9 13880.6 13898.3 14129.0 14405.5 4655.4 4685.1 4722.6 4735.3 4808.2 77697.6 79253.2 79347.6 79581.6 79703.2 11387.2 12173.5 12298.0 12515.9 13000.6 21566.1 21984.7 21999.0 21999.6 22000.2 9245.8 9314.9 9461.9 9523.0 9558.1 5061.4 5101.8 5118.6 5161.9 5165.7 3871.7 4095.8 4100.9 4123.7 4134.7 675.7 686.3 692.2 693.7 697.5 766.1 792.9 808.7 811.7 825.2 10933.8 11069.8 11117.4 11332.0 11347.3 785.5 808.7 814.3 819.7 840.1 4640.3 4704.5 4747.9 4791.5 4807.1 120665.0 123628.4 124782.4 130250.3 132913.0 107140.0 109864.9 110000.0 110161.0 111413.0 60503.7 61491.0 62120.0 62187.0 62302.4 9390.0 9616.3 9669.9 9673.7 9939.4 43038.8 44217.6 52007.0 52357.0 59914.0 50810.2 50945.2 51183.7 52479.2 73334.6 12752.9 12815.1 12915.8 13041.2 13068.2 14184.3 15332.3 15921.8 16304.8 16325.2 10442.0 12262.8 14870.9 14893.5 15197.5 12646.2 13621.5 13788.2 14491.6 14635.0 14951.6 15065.2 15231.8 15238.2 16054.7 11019.2 11092.4 11107.7 11168.5 11750.4 11527.3 12322.5 12381.0 12492.1 12609.6 07:20:58 INFO - PROCESS | 25517 | -------- Summary: end -------- 07:20:58 INFO - PROCESS | 25517 | 07:20:59 INFO - TEST-INFO | 25517: exit 0 07:20:59 INFO - TEST-OK | dromaeo_css | took 1113473ms 07:20:59 INFO - TEST-START | kraken 07:20:59 INFO - operating with platform_type : linux_ 07:20:59 INFO - Initialising browser for kraken test... 07:20:59 INFO - TEST-INFO | started process 25707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnRMGcs/profile http://localhost:35899/getInfo.html) 07:21:03 INFO - PROCESS | 25707 | __metrics Screen width/height:1600/1200 07:21:03 INFO - PROCESS | 25707 | colorDepth:24 07:21:03 INFO - PROCESS | 25707 | Browser inner width/height: 1024/697 07:21:03 INFO - PROCESS | 25707 | __metrics 07:21:04 ERROR - PROCESS | 25707 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:21:04 INFO - PROCESS | 25707 | console.error: PushService: 07:21:04 INFO - PROCESS | 25707 | stateChangeProcessEnqueue: Error transitioning state 07:21:04 INFO - PROCESS | 25707 | UnknownError 07:21:05 INFO - TEST-INFO | 25707: exit 0 07:21:05 INFO - Browser initialized. 07:21:05 INFO - Running cycle 1/1 for kraken test... 07:21:05 INFO - TEST-INFO | started process 25796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnRMGcs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:21:17 INFO - PROCESS | 25796 | RSS: Main: 145592320 07:21:17 INFO - PROCESS | 25796 | 07:21:19 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35899/tests/kraken/driver.html?testName=audio-beat-detection) 07:21:20 INFO - PROCESS | 25796 | RSS: Main: 194764800 07:21:20 INFO - PROCESS | 25796 | 07:21:23 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35899/tests/kraken/driver.html?testName=audio-dft) 07:21:23 INFO - PROCESS | 25796 | RSS: Main: 192880640 07:21:23 INFO - PROCESS | 25796 | 07:21:26 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35899/tests/kraken/driver.html?testName=audio-fft) 07:21:27 INFO - PROCESS | 25796 | RSS: Main: 278560768 07:21:27 INFO - PROCESS | 25796 | 07:21:29 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35899/tests/kraken/driver.html?testName=audio-oscillator) 07:21:29 INFO - PROCESS | 25796 | RSS: Main: 188284928 07:21:29 INFO - PROCESS | 25796 | 07:21:31 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35899/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:21:32 INFO - PROCESS | 25796 | RSS: Main: 188608512 07:21:32 INFO - PROCESS | 25796 | 07:21:35 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35899/tests/kraken/driver.html?testName=imaging-darkroom) 07:21:36 INFO - PROCESS | 25796 | RSS: Main: 223232000 07:21:36 INFO - PROCESS | 25796 | 07:21:39 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35899/tests/kraken/driver.html?testName=imaging-desaturate) 07:21:39 INFO - PROCESS | 25796 | RSS: Main: 223133696 07:21:39 INFO - PROCESS | 25796 | 07:21:42 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35899/tests/kraken/driver.html?testName=json-parse-financial) 07:21:43 INFO - PROCESS | 25796 | RSS: Main: 223158272 07:21:43 INFO - PROCESS | 25796 | 07:21:44 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35899/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:21:45 INFO - PROCESS | 25796 | RSS: Main: 200777728 07:21:45 INFO - PROCESS | 25796 | 07:21:47 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:21:47 INFO - PROCESS | 25796 | RSS: Main: 202362880 07:21:47 INFO - PROCESS | 25796 | 07:21:49 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:21:50 INFO - PROCESS | 25796 | RSS: Main: 209223680 07:21:50 INFO - PROCESS | 25796 | 07:21:52 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:21:53 INFO - PROCESS | 25796 | RSS: Main: 212496384 07:21:53 INFO - PROCESS | 25796 | 07:21:56 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:21:56 INFO - PROCESS | 25796 | RSS: Main: 213778432 07:21:56 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | Cycle 1(1): loaded http://localhost:35899/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35899/tests/kraken/driver.html?testName=ai-astar) 07:21:58 INFO - PROCESS | 25796 | RSS: Main: 257376256 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | __start_tp_report 07:21:58 INFO - PROCESS | 25796 | _x_x_mozilla_page_load 07:21:58 INFO - PROCESS | 25796 | _x_x_mozilla_page_load_details 07:21:58 INFO - PROCESS | 25796 | |i|pagename|runs| 07:21:58 INFO - PROCESS | 25796 | |0;ai-astar;111;105;111;110;111;110;111;112;109;110 07:21:58 INFO - PROCESS | 25796 | |1;audio-beat-detection;149;125;125;197;125;132;151;135;151;132 07:21:58 INFO - PROCESS | 25796 | |2;audio-dft;160;161;161;160;163;162;160;163;161;162 07:21:58 INFO - PROCESS | 25796 | |3;audio-fft;75;75;76;121;76;76;76;75;113;75 07:21:58 INFO - PROCESS | 25796 | |4;audio-oscillator;85;84;114;84;84;83;122;84;84;127 07:21:58 INFO - PROCESS | 25796 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;117;117 07:21:58 INFO - PROCESS | 25796 | |6;imaging-darkroom;145;148;146;146;147;145;145;146;145;145 07:21:58 INFO - PROCESS | 25796 | |7;imaging-desaturate;94;95;95;94;95;95;95;95;95;95 07:21:58 INFO - PROCESS | 25796 | |8;json-parse-financial;50;50;51;50;52;50;52;50;49;51 07:21:58 INFO - PROCESS | 25796 | |9;json-stringify-tinderbox;62;59;59;60;60;59;60;60;60;60 07:21:58 INFO - PROCESS | 25796 | |10;stanford-crypto-aes;77;78;76;76;77;76;76;77;110;76 07:21:58 INFO - PROCESS | 25796 | |11;stanford-crypto-ccm;127;121;117;128;132;118;123;121;125;127 07:21:58 INFO - PROCESS | 25796 | |12;stanford-crypto-pbkdf2;159;154;156;156;153;154;157;155;154;157 07:21:58 INFO - PROCESS | 25796 | |13;stanford-crypto-sha256-iterative;63;61;64;62;62;63;63;60;59;62 07:21:58 INFO - PROCESS | 25796 | __end_tp_report 07:21:58 INFO - PROCESS | 25796 | __start_cc_report 07:21:58 INFO - PROCESS | 25796 | _x_x_mozilla_cycle_collect,1614 07:21:58 INFO - PROCESS | 25796 | __end_cc_report 07:21:58 INFO - PROCESS | 25796 | __startTimestamp1467037318642__endTimestamp 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | ------- Summary: start ------- 07:21:58 INFO - PROCESS | 25796 | Number of tests: 14 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:1.94 (1.8%) stddev-sans-first:2.03 07:21:58 INFO - PROCESS | 25796 | Values: 111.0 105.0 111.0 110.0 111.0 110.0 111.0 112.0 109.0 110.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:142.00 stddev:21.99 (15.5%) stddev-sans-first:23.18 07:21:58 INFO - PROCESS | 25796 | Values: 149.0 125.0 125.0 197.0 125.0 132.0 151.0 135.0 151.0 132.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.16 (0.7%) stddev-sans-first:1.13 07:21:58 INFO - PROCESS | 25796 | Values: 160.0 161.0 161.0 160.0 163.0 162.0 160.0 163.0 161.0 162.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#3] audio-fft Cycles:10 Average:83.80 Median:75.50 stddev:17.61 (23.3%) stddev-sans-first:18.38 07:21:58 INFO - PROCESS | 25796 | Values: 75.0 75.0 76.0 121.0 76.0 76.0 76.0 75.0 113.0 75.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.00 stddev:18.14 (21.6%) stddev-sans-first:18.87 07:21:58 INFO - PROCESS | 25796 | Values: 85.0 84.0 114.0 84.0 84.0 83.0 122.0 84.0 84.0 127.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:21:58 INFO - PROCESS | 25796 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 07:21:58 INFO - PROCESS | 25796 | Values: 145.0 148.0 146.0 146.0 147.0 145.0 145.0 146.0 145.0 145.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 07:21:58 INFO - PROCESS | 25796 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 07:21:58 INFO - PROCESS | 25796 | Values: 50.0 50.0 51.0 50.0 52.0 50.0 52.0 50.0 49.0 51.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.88 (1.5%) stddev-sans-first:0.50 07:21:58 INFO - PROCESS | 25796 | Values: 62.0 59.0 59.0 60.0 60.0 59.0 60.0 60.0 60.0 60.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:76.50 stddev:10.60 (13.9%) stddev-sans-first:11.19 07:21:58 INFO - PROCESS | 25796 | Values: 77.0 78.0 76.0 76.0 77.0 76.0 76.0 77.0 110.0 76.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:126.00 stddev:4.75 (3.8%) stddev-sans-first:4.90 07:21:58 INFO - PROCESS | 25796 | Values: 127.0 121.0 117.0 128.0 132.0 118.0 123.0 121.0 125.0 127.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.50 Median:156.00 stddev:1.84 (1.2%) stddev-sans-first:1.45 07:21:58 INFO - PROCESS | 25796 | Values: 159.0 154.0 156.0 156.0 153.0 154.0 157.0 155.0 154.0 157.0 07:21:58 INFO - PROCESS | 25796 | 07:21:58 INFO - PROCESS | 25796 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.50 stddev:1.52 (2.4%) stddev-sans-first:1.56 07:21:58 INFO - PROCESS | 25796 | Values: 63.0 61.0 64.0 62.0 62.0 63.0 63.0 60.0 59.0 62.0 07:21:58 INFO - PROCESS | 25796 | -------- Summary: end -------- 07:21:58 INFO - PROCESS | 25796 | 07:21:59 INFO - TEST-INFO | 25796: exit 0 07:21:59 INFO - TEST-OK | kraken | took 60381ms 07:21:59 INFO - SUITE-END | took 1173s 07:21:59 INFO - Completed test suite (00:19:34) 07:21:59 INFO - javascript benchmark 07:21:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3387.6612338766126, 3412.334594754024, 3417.350703366361, 3447.1382154461385, 3467.5838899332084, 5626.212330273765, 5638.154276858473, 5697.515711164556, 5701.885962280752, 5719.628224165426, 5667.688277144755, 5720.914186287209, 5721.799737009205, 5725.14122881568, 5730.0, 5014.648974571782, 5062.873428164288, 5140.383154021513, 5190.584753219752, 5192.974035129819, 5169.534741873231, 5212.973935130319, 5218.191180367049, 5219.295395121648, 5226.451222621627, 5111.309973153632, 5125.897482050356, 5153.9742301288625, 5175.327207463025, 5193.58451323895, 5244.8426547203835, 5264.973675131638, 5266.947330526684, 5268.841934741964, 5303.124984377573, 4981.775820088069, 5023.824166154167, 5108.923366149511, 5169.53474187325, 5172.301739265226, 5515.586331025189, 5522.502974732293, 5529.170624406331, 5536.031194540939, 5543.445655434464, 6135.570510064297, 6144.446999770043, 6148.231471066117, 6152.3847615238565, 6161.784337548164, 6045.72794224261, 6050.0, 6057.242844644419, 6119.969400152971, 6195.5663103582765, 5711.857203569903, 5735.053716136832, 5767.509761670263, 5768.278965129359, 5768.509676677446, 6052.213212282463, 6266.436020758154, 6280.905786413207, 6282.780102696383, 6284.717187726564, 5939.168516407706, 6062.575619706667, 6064.0, 6069.119977603311, 6069.180660610849, 6108.9733031877, 6120.101005573244, 6139.4001448443105, 6140.565599444434, 6144.279601711528, 5923.763049477972, 5980.252468441445, 6121.540884433596, 6125.754969801158, 6144.016957286813, 6031.9698401508585, 6085.756569737249, 6093.9390606093375, 6094.725737341831, 6133.785317513865, 4609.446866375989, 4614.238650622609, 4622.46841613212, 4623.514530974199, 4652.860414187579, 4088.652464540488, 4127.7936103194725, 4150.439690641785, 4155.168966206771, 4172.395002724648, 5761.3950535194035, 5783.074708046776, 5784.363719990798, 5786.971065144648, 5811.564132690065, 6164.568480206342, 6200.193974783339, 6206.844828879314, 6209.720562574695, 6217.564770466024, 5624.690642014729, 5752.194692743019, 5793.073108302651, 5797.391273916262, 5843.923037087227, 5325.440828713007, 5331.0, 5331.813386531453, 5336.092864213093, 5336.519713225828, 6419.358064193543, 6422.614643121335, 6424.38968298011, 6431.228252609717, 6433.324500927335, 5958.523318134452, 6109.358517355791, 6119.350229749377, 6127.5866022601795, 6235.594686345373, 6397.936020639921, 6400.135981642605, 6430.807075787734, 6439.034144878306, 6448.967755161194, 5897.5576831736935, 6093.908591371044, 6099.329073801967, 6101.511879049576, 6106.816795496142, 6028.5177185825905, 6057.061155520902, 6079.118527813531, 6131.386861313833, 6142.201513803177, 5967.671778052249, 5969.313528944053, 5977.402259773988, 5981.192539007178, 5981.461668449861, 5847.473727364558, 5856.824295271149, 5888.116782482491, 5982.982892908303, 5985.191999080069, 5809.82570522885, 5914.7338369774325, 5920.851978700567, 5929.525637948867, 5964.970175149097, 6015.789447369321, 6033.185519954925, 6074.483668888194, 6079.604825686316, 6082.391760823883, 5695.174199741097, 5761.913571296518, 5788.392218816964, 5903.940960590511, 5913.113033045078], "value": 5634.304025401906, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.0925275965194, 656.8653426047659, 660.7456129390187, 668.6623255256095, 670.4200866250694, 33343.166420839494, 35839.82080089599, 36403.0, 45270.32094518577, 46106.769466152706, 47747.28379074308, 48254.51745482544, 48342.51657483424, 48364.0, 48392.75803620986, 8986.730398088068, 9129.543522823833, 9157.725268241964, 9164.72505824823, 9172.908270917273, 8407.411481196319, 10611.151107911388, 10888.183386246063, 10894.727631809228, 10914.508847101899, 10601.0, 10673.466326683634, 10676.412797296145, 11051.060659843923, 11068.446577671084, 8360.0, 10692.679219623345, 10908.50911708971, 10991.780164396667, 10993.0, 10431.0, 10740.409277489734, 10753.892461075446, 10771.892281077167, 10774.245802793808, 44921.101577968584, 44995.325070123974, 45076.09847803025, 45246.77376613096, 45337.0, 3859.749116307459, 3878.6703130233964, 4247.150569886005, 4394.868153955386, 4448.1770872388715, 30574.5235428786, 31014.0, 31230.84384578085, 32173.1956701082, 32548.511772323433, 4634.490206077363, 4731.716097034189, 4736.265878788793, 4787.6648634595595, 4821.156297647897, 380818.0, 381982.09008955053, 382274.0, 382782.0, 383490.0, 38931.610683892795, 39298.41052384216, 70496.0, 71305.0, 71518.2848171522, 41758.37362439566, 41858.0, 41906.37140442896, 41933.1613367737, 41937.58062419398, 17925.28298868031, 18101.7284740729, 18111.0, 18140.365087221882, 18171.364002259856, 13133.802992955114, 14875.70248595022, 15097.698046039237, 15211.087334759728, 15266.770998435033, 9279.860802087975, 9348.392354496937, 9389.62441502346, 9392.8591071134, 9449.811003779885, 3491.528643633128, 3553.271579326232, 3567.910802229965, 3582.4089025311055, 3591.533100696945, 1012.7417508535424, 1090.465671820802, 1106.5794997900746, 1175.2008634128874, 1240.7368620528134, 30805.537916931265, 31080.533791993137, 31211.531827022613, 31484.21289467736, 31674.841625792185, 1161.6689243565543, 1188.869224385317, 1202.7233736240714, 1239.8264243005985, 1254.667513109027, 16841.41055063067, 16873.57816054584, 17049.573760655836, 17166.0, 17326.653466930842, 631406.0, 635592.0, 635694.0, 636969.0, 641706.0, 64217.678911605144, 64776.0, 64987.35012649908, 64999.0, 65047.34952650507, 1311.2132720367895, 1406.528812847687, 1419.7160567886258, 1450.376338174595, 1462.5027490653229, 34006.3198736019, 35670.0, 36068.45897311595, 36104.458433123, 36394.45408318824, 53807.73096134494, 54497.72751136219, 54520.0, 54583.0, 54669.453305466435, 442997.0, 443379.0, 443987.0, 445037.0, 508379.0, 31204.37591248117, 31694.841525792224, 31983.36033279368, 33096.1725956853, 33106.33787324288, 53519.19721204263, 53752.73123634357, 54093.0, 54862.0, 55503.0, 44471.55528444674, 46017.22601722575, 46319.0, 46588.30117548307, 47095.52904470912, 45149.0, 45290.0, 45790.542094578625, 46048.76975615101, 47593.52406476029, 44749.32876006928, 45325.773371132935, 45515.772421137684, 45718.54281457276, 46047.539524605665, 38663.806680966416, 39792.602073980044, 40713.0, 41113.79443102765, 41540.96147596334, 29567.26081847971, 30331.241718957204, 30416.0, 30625.540616890317, 30792.53811192789], "value": 20739.407097108582, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.9415528903598, 435.23512158198514, 439.60216004515917, 440.9250427427337, 442.88263610143315, 18705.0, 19099.52251193719, 20159.496012599673, 20247.088881000353, 20293.28973485928, 18891.90554047228, 19839.702404463944, 19854.205831766747, 19988.90005549974, 20472.385828425133, 5142.460041695625, 5337.412884582693, 5372.623916325859, 5379.6503227290295, 5382.6770393776305, 5233.555147812441, 5327.0, 5372.247885296062, 5567.49892509674, 5845.970770146143, 5699.059655156894, 5783.681897495636, 5802.535797136219, 5825.213596164527, 5831.737571809279, 5336.866578335534, 5338.492843179931, 5473.17902314652, 5715.3141623005295, 5808.796692115777, 5091.745412729386, 5209.739513024371, 5217.321748172713, 5220.686758794484, 5243.632945693803, 14412.207328596924, 15149.697006059927, 22231.555368892692, 23358.065677373055, 23430.297091087294, 2271.216430331533, 2302.919397821085, 2329.219711396684, 2348.8708121053337, 2829.7311755383207, 10388.0, 10620.097291730212, 10867.728306792314, 10887.782244355069, 13646.590602281947, 1801.2704854533936, 1802.1766806857997, 1822.56928311275, 1881.5766452548205, 1980.6632872411724, 86457.0, 88931.55534222352, 89975.10024899733, 89986.10013899843, 90393.0, 11527.596534121432, 11675.299482031105, 11796.646100616996, 11944.0, 12021.819672704916, 16722.24749886258, 16794.83205167933, 16803.831961680226, 16818.0, 16821.327146913987, 8879.911200888038, 9147.0, 9208.677696280598, 9293.349465537347, 9322.550656257092, 9099.272058235458, 9173.770655733526, 9188.540572971324, 9445.291603129794, 9500.714978550655, 4941.2835138905375, 4999.075171093359, 5073.162928116893, 5077.543021128116, 5091.592672586185, 2034.2574960139443, 2056.969145462819, 2077.761057478379, 2101.9175124810736, 2147.7422709274674, 831.4512421801703, 975.7072878136672, 1062.654637242899, 1087.7063192937862, 1100.5047728522197, 18515.81484185168, 18615.696901217056, 19576.412707618794, 20105.597888042157, 21272.149114035576, 1025.707673313102, 1029.8764148302103, 1035.4667346316653, 1056.984145237822, 1092.8579284693099, 10811.297265677706, 11349.035331996707, 11349.716257093638, 11380.658580242605, 11397.08823294151, 500902.0, 505463.0, 511769.0, 515876.0, 522137.0, 71155.28844711487, 83737.58131209305, 84113.15886841176, 98532.50733746428, 101650.49174754079, 501.52551498594016, 520.2794130129722, 1066.6906597086759, 1092.1317552545745, 1105.5080489182237, 11117.0, 11289.887101129212, 11732.648020559396, 11886.465109069939, 11994.220375675553, 39993.60006399899, 40899.38650920298, 40965.287803027044, 40988.79505602453, 41605.58394416017, 59299.40700592939, 59681.701591491765, 59787.701061494416, 60288.69855650693, 60765.69617151886, 8372.455790373606, 8588.65645374178, 8633.955033979015, 8794.164554367326, 8794.384393092421, 9398.389104708172, 12975.351232438152, 13548.525801596898, 13769.03616746818, 14057.086289391156, 10914.280581642633, 12149.69625759363, 12240.75518489643, 12333.691657708629, 12483.500659973499, 8769.561521924006, 8778.91221087781, 8966.68616598416, 11994.76010479803, 12304.138710290194, 8670.393072485118, 8924.152205540722, 8951.194392504705, 9074.45553266806, 9271.397359171633, 13019.609411717664, 13415.26216058087, 13535.59393218205, 13625.65935851572, 13763.724725505233, 12098.274103553815, 12186.2078964867, 12414.565490207799, 12416.255024698547, 12769.744605108031], "value": 9279.805111033897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.8360288566819, 1550.5735922621277, 1606.4859245041587, 1806.223323970693, 1938.2053358123173, 9446.905530944689, 9787.363821351624, 9788.510574471284, 9800.019998000196, 9808.754781130476, 9487.667931622394, 9691.903080969189, 9723.173530249936, 9729.854052189223, 9736.756581085476, 2206.7462241842154, 2473.2332976777257, 2509.82431229814, 2525.4570267392505, 2537.1627362970266, 2558.09187738353, 2564.781993530543, 2566.0633868637906, 2567.6918769747563, 2569.871506424681, 2527.810414218932, 2548.4011257354505, 2550.2859199424097, 2551.9489610207784, 2552.6553915221484, 2145.8605190662556, 2410.4576470294132, 2503.824732268742, 2503.9374015649573, 2526.823122381434, 2398.4963157736897, 2414.7585241475886, 2426.1751004658327, 2469.802415806731, 2474.9628755568683, 9541.666041688508, 9575.0, 9598.808023839483, 9603.855942160873, 9610.135087842127, 5760.8271751847515, 5878.441548052928, 5880.26496687914, 5912.822615321547, 5916.083007133858, 3602.963970360315, 3625.963740362589, 3695.946655203254, 3731.925361492782, 3765.2281282337053, 1216.8113942338953, 1229.7970834812245, 1264.3488603369271, 1333.593254057512, 1345.8855997240246, 14107.506237281748, 14139.222342771143, 14157.433702651882, 14158.150510969272, 14158.929205353907, 5121.795128194831, 5156.690598564098, 5172.250023746536, 5180.197069454241, 5256.553192978639, 11425.714357140972, 11560.479778409992, 11563.42182890852, 11580.826287605692, 11595.420228988685, 5057.190849464068, 5582.939241544093, 5658.91511627326, 5667.064934285879, 5667.234923285304, 4637.211674015425, 4723.811047558059, 4769.2130798417875, 5440.700761458079, 5447.918281225785, 4383.0, 4389.868303950887, 4494.910101798011, 4548.0, 4557.361969324297, 1393.5540626999466, 1424.1312799192485, 1427.2863568215892, 1429.6783223774567, 1444.3572610188364, 1002.8395456726888, 1013.7212266626589, 1020.5764607687727, 1024.6618615856748, 1024.933379330341, 6372.617642941438, 6471.320511346335, 6506.284308726036, 6659.500537459709, 6701.530892837454, 498.88026873550086, 512.4542362384049, 513.1840373805593, 519.8258583374536, 520.2014907117632, 4536.4102666653125, 4552.317152427162, 4564.7032942858605, 4610.331501932268, 4619.7757594237555, 11659.0, 11904.345261010727, 12410.069244806675, 12417.19288246261, 12443.191192572454, 12078.637640870787, 12326.81509777354, 12456.688582785502, 12463.376831158406, 12473.563425280072, 8971.05803890595, 9143.268538516902, 9153.908460915305, 9174.403663761834, 9176.31177661678, 6539.378759017885, 6762.289959554176, 6762.830929226809, 6866.0, 6868.896966545406, 11169.162312826407, 11358.659240222476, 11491.597794077166, 11689.357085360387, 11722.70693232676, 11485.885141148481, 11689.415529223335, 11726.706832328919, 11890.405479725807, 11896.167268291136, 7776.805579860323, 8090.83818323642, 8277.83444331122, 8342.499450033018, 8584.785380365542, 9476.384035037701, 9476.52617369115, 9553.474558899326, 9558.330916835755, 9569.952150239204, 8421.0, 8557.743267701979, 8561.272291855263, 8571.271441927505, 8666.523341216294, 8421.915780842113, 8460.153984601491, 8464.0, 8487.957560212159, 8574.3997920145, 7350.338469537767, 7416.110066792019, 7417.962910185414, 7425.888611670937, 7429.108506979197, 5370.919436208538, 5441.319835020622, 5506.449355064461, 5563.527100196528, 5698.715064246854, 5540.1135818270495, 5566.359868614999, 5605.747741351731, 5638.323401191883, 5679.233303503974], "value": 5003.076329238168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.6287147009759, 345.2111924253081, 350.224253278987, 350.73344258363636, 357.19809028730504, 5487.478689524493, 5529.894021195757, 5548.472895074967, 5557.138643510258, 5558.638688485245, 5473.808416705416, 5490.478404551562, 5490.588205884555, 5511.200875872996, 5519.503244707976, 4162.666986641061, 4340.283853164224, 4342.435483387171, 4343.739375637456, 4346.97826510867, 4289.4209281747035, 4316.892077698051, 4327.177836211125, 4329.0, 4332.28517294646, 4047.919041619166, 4308.224519586473, 4315.50371707253, 4318.287482565373, 4326.351047342908, 4152.418661387408, 4291.506476755167, 4294.9785251073545, 4300.161468513641, 4311.805968731414, 3776.3580191367532, 3800.7339486235974, 3807.390817469219, 3819.217060502618, 3836.9040773980596, 5060.822871199491, 5167.940572182693, 5260.89607131794, 5310.389305229871, 5329.333833270841, 4645.465771436295, 4949.455559888409, 4972.5524702776565, 4978.7844409237905, 4979.850604481871, 5751.741171647244, 5773.364929857712, 5776.277965254343, 5779.971100144515, 5794.246747922785, 4177.97911010446, 4241.130568233536, 4253.574642535722, 4259.61663450291, 4279.786010699484, 5965.164876917235, 6002.249718785152, 6018.8495287617725, 6025.367336429656, 6038.33578306386, 4341.3487976803735, 4362.0, 4362.323839804835, 4362.738235705868, 4366.563343665608, 4316.654667626645, 4365.978170109129, 4385.6710746694125, 4387.670924680661, 4414.139242847614, 4878.731669758126, 4931.3589233399425, 4947.480514545993, 4956.90086198274, 4960.553550180501, 5780.583756979483, 5811.593188476851, 5823.854403639943, 5824.417558244227, 5834.212381328552, 1479.634166098024, 1496.895217334798, 1523.2612183091192, 1526.8015158029607, 1530.6785575029146, 964.62379671928, 971.2618410008445, 972.4311277902365, 984.8821587498242, 1001.2940876682068, 4981.551660350586, 4992.301077849104, 5030.874228144326, 5065.898682026413, 5066.949330506722, 5245.475452454724, 5311.229871668586, 5314.4951229633125, 5370.610078515023, 5372.247885296062, 5087.669301495391, 5479.917801232985, 5488.9725551372785, 5593.496585307342, 5686.3744988051285, 3154.747620190379, 3154.921126971844, 3157.163351711798, 3170.9682903171133, 3184.028871194308, 5949.345571987078, 6139.956207444745, 6145.754169833246, 6152.78465253714, 6243.906341404882, 6093.573449858467, 6097.786577469856, 6099.390060993954, 6100.267967843887, 6128.445501609056, 6185.597936134137, 6192.133101365723, 6227.19046523949, 6263.96868015657, 6264.874702505925, 5681.0, 5997.820065398045, 6046.697665116639, 6048.274207095177, 6053.9091913622215, 5800.8826778226485, 5929.703514824328, 5979.970100149471, 6009.617787908718, 6011.6693581853415, 5926.1925112841, 6225.937740622536, 6290.528210384331, 6352.872942541031, 6368.42684158428, 4302.892427689208, 4315.748432954408, 4332.566743325642, 4349.3475978603465, 4356.716813407118, 6163.568550201443, 6178.505719542516, 6180.81457556274, 6182.196314479089, 6195.721192546436, 5705.030144875464, 5949.8215053548465, 5961.523078153824, 5962.970185149046, 5967.433093856077, 5914.467697907209, 5928.0, 5934.97032514852, 5937.435943585352, 5968.492678122235, 5648.943510564842, 5829.464183080374, 5849.181114643869, 5859.5019423349495, 5883.705814709333, 5842.298924129132, 5884.705764711662, 5905.881882362242, 5907.223604905687, 5991.310999235144, 5449.891002179855, 5783.566232532493, 5791.407362975216, 5825.883482330414, 5832.445917637818], "value": 4429.039485709518, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.4353921637205, 820.81941972766, 871.3421366868015, 874.3398733955862, 932.7775657578445, 15763.21183940804, 15956.521304360873, 15973.760393594106, 15982.200889955498, 16879.662406751857, 16322.673546529062, 16840.915795421035, 16849.578760530992, 16889.324427022904, 16897.83102168981, 870.4864130163203, 870.495112834556, 874.3442418186361, 874.4884242718017, 874.903760586335, 738.4830618567024, 863.7365603490932, 886.5301390263171, 891.6522556203054, 893.63361021981, 916.9128932751378, 917.3303488453433, 918.9770255743593, 920.1626519866923, 920.1626519866923, 765.9846803063963, 910.3172620534599, 912.2884150362695, 912.4479689787682, 912.6623149434686, 666.2371584535728, 676.577139287945, 679.5447050476192, 680.9829754256183, 681.6114814555705, 15347.4628388007, 15383.692326153414, 15429.459968901034, 15436.614084647861, 15443.150626715526, 14342.92828535861, 14888.330025148894, 14896.776548351783, 14915.403383864632, 14935.327910244065, 13812.861871381258, 13880.583582492542, 13898.330463427395, 14129.010969232158, 14405.495807646683, 4655.37152484417, 4685.086408150412, 4722.574968252874, 4735.266033764772, 4808.230683090724, 77697.61151194264, 79253.20746792515, 79347.60326198333, 79581.60209198974, 79703.20296797073, 11387.202895797214, 12173.452194651261, 12298.0, 12515.937420312841, 13000.609981700563, 21566.137354505958, 21984.670229946245, 21999.0, 21999.560008799734, 22000.229991950204, 9245.768855778659, 9314.86027709585, 9461.858072128924, 9523.0, 9558.116719024105, 5061.44324124346, 5101.770420331094, 5118.564921981673, 5161.896762064737, 5165.74171291434, 3871.7483363581277, 4095.7542547447247, 4100.897477563085, 4123.670106391482, 4134.73124246929, 675.6520391998125, 686.3034020469228, 692.1624833950875, 693.6947742993067, 697.4908316928569, 766.0768773627765, 792.8731402975496, 808.7174989222018, 811.6996711216769, 825.1707034430359, 10933.781324373627, 11069.833952490719, 11117.388543630179, 11332.0, 11347.262427942158, 785.5443842571409, 808.6603626476893, 814.2518998380466, 819.7212947597845, 840.0843081041572, 4640.32715256286, 4704.458987216445, 4747.905041899212, 4791.496892826272, 4807.062622788592, 120665.0, 123628.38185809013, 124782.37608811898, 130250.34874825754, 132913.0, 107140.0, 109864.94217137172, 110000.0, 110161.0, 111413.0, 60503.69748151319, 61490.999999999105, 62120.0, 62187.0, 62302.37697622966, 9390.0, 9616.278779091455, 9669.9033009669, 9673.661421850202, 9939.353941993748, 43038.78480607577, 44217.55782442135, 52007.0, 52357.0, 59914.0, 50810.23784643159, 50945.23582146345, 51183.74408127936, 52479.21281180709, 73334.63332683516, 12752.936235318764, 12815.06225326462, 12915.806262906252, 13041.152325098838, 13068.215907045607, 14184.277092835464, 15332.310046048178, 15921.840781592036, 16304.836951630807, 16325.183740813149, 10442.00800923911, 12262.816057759319, 14870.851291486946, 14893.47872824446, 15197.468088616846, 12646.177998430441, 13621.523246686318, 13788.172709637454, 14491.637709056937, 14635.0, 14951.626209344853, 15065.246737662854, 15231.847681523042, 15238.238088095773, 16054.678906421572, 11019.228653994143, 11092.445377730919, 11107.72230694239, 11168.497417616065, 11750.353730544903, 11527.250728702607, 12322.507099715911, 12381.018550684246, 12492.125551211328, 12609.558665446666], "value": 7953.872264042824, "unit": "score"}], "name": "dromaeo_css", "value": 7589.682662999057, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 110.0, 111.0, 110.0, 111.0, 112.0, 109.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 125.0, 125.0, 197.0, 125.0, 132.0, 151.0, 135.0, 151.0, 132.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 163.0, 162.0, 160.0, 163.0, 161.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 121.0, 76.0, 76.0, 76.0, 75.0, 113.0, 75.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 114.0, 84.0, 84.0, 83.0, 122.0, 84.0, 84.0, 127.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 148.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 52.0, 50.0, 52.0, 50.0, 49.0, 51.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 76.0, 77.0, 76.0, 76.0, 77.0, 110.0, 76.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 121.0, 117.0, 128.0, 132.0, 118.0, 123.0, 121.0, 125.0, 127.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 156.0, 156.0, 153.0, 154.0, 157.0, 155.0, 154.0, 157.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 64.0, 62.0, 62.0, 63.0, 63.0, 60.0, 59.0, 62.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1481.4000000000003, "alertThreshold": 2.0}]} 07:21:59 INFO - Return code: 0 07:21:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:21:59 ERROR - # TBPL SUCCESS # 07:21:59 INFO - Running post-action listener: _resource_record_post_action 07:21:59 INFO - ##### 07:21:59 INFO - ##### Finished run-tests step (success) 07:21:59 INFO - ##### 07:21:59 INFO - Running post-run listener: _resource_record_post_run 07:22:00 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504557568; Read time: 12; Write time: 549500 07:22:00 INFO - TinderboxPrint: CPU usage
13.1% 07:22:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:22:00 INFO - TinderboxPrint: I/O write bytes / time
504,557,568 / 549,500 07:22:00 INFO - TinderboxPrint: CPU idle
8,261.9 (86.9%) 07:22:00 INFO - TinderboxPrint: CPU user
1,198.0 (12.6%) 07:22:00 INFO - TinderboxPrint: Swap in / out
0 / 0 07:22:00 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252411904; Read time: 0; Write time: 324352 07:22:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251981824; Read time: 12; Write time: 225112 07:22:00 INFO - Running post-run listener: _upload_blobber_files 07:22:00 INFO - Blob upload gear active. 07:22:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:22:00 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 07:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:22:01 INFO - (blobuploader) - INFO - Open directory for files ... 07:22:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:22:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:03 INFO - (blobuploader) - INFO - Done attempting. 07:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:22:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:05 INFO - (blobuploader) - INFO - Done attempting. 07:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:22:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:08 INFO - (blobuploader) - INFO - Done attempting. 07:22:08 INFO - (blobuploader) - INFO - Iteration through files over. 07:22:08 INFO - Return code: 0 07:22:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:22:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:22:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dddad70b324ae025be8a6a097f56259e41706855aef7e77bd60c4e919807263dad64614412488d9565ba01b5f94388437da2972c0e401ded376ae3f8a4dbc9cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db3ec9077d3240efd371f50570582485ef976417d2250d7fa71453e166df57b1388721af4a3915319376f960ac5ad1b02442958b5d95aa46a8a9d15d9946df6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ef4aeeb2e5de2910057f7e29a793dfce5e4254212ad3243fcbb19d54620ec1e49af0cea53f941c751b60532fdca4b2e94b3192221b3a1c915d2cf43aa9fd7ad"} 07:22:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:22:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:22:08 INFO - Contents: 07:22:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dddad70b324ae025be8a6a097f56259e41706855aef7e77bd60c4e919807263dad64614412488d9565ba01b5f94388437da2972c0e401ded376ae3f8a4dbc9cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db3ec9077d3240efd371f50570582485ef976417d2250d7fa71453e166df57b1388721af4a3915319376f960ac5ad1b02442958b5d95aa46a8a9d15d9946df6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ef4aeeb2e5de2910057f7e29a793dfce5e4254212ad3243fcbb19d54620ec1e49af0cea53f941c751b60532fdca4b2e94b3192221b3a1c915d2cf43aa9fd7ad"} 07:22:08 INFO - Running post-run listener: copy_logs_to_upload_dir 07:22:08 INFO - Copying logs to upload dir... 07:22:08 INFO - mkdir: /builds/slave/test/build/upload/logs 07:22:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.281375 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-27 07:22:08.425070) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 07:22:08.428849) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dddad70b324ae025be8a6a097f56259e41706855aef7e77bd60c4e919807263dad64614412488d9565ba01b5f94388437da2972c0e401ded376ae3f8a4dbc9cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db3ec9077d3240efd371f50570582485ef976417d2250d7fa71453e166df57b1388721af4a3915319376f960ac5ad1b02442958b5d95aa46a8a9d15d9946df6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ef4aeeb2e5de2910057f7e29a793dfce5e4254212ad3243fcbb19d54620ec1e49af0cea53f941c751b60532fdca4b2e94b3192221b3a1c915d2cf43aa9fd7ad"} build_url:https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/Zo1l7zWrQv-mgYwldJWUFA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dddad70b324ae025be8a6a097f56259e41706855aef7e77bd60c4e919807263dad64614412488d9565ba01b5f94388437da2972c0e401ded376ae3f8a4dbc9cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db3ec9077d3240efd371f50570582485ef976417d2250d7fa71453e166df57b1388721af4a3915319376f960ac5ad1b02442958b5d95aa46a8a9d15d9946df6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ef4aeeb2e5de2910057f7e29a793dfce5e4254212ad3243fcbb19d54620ec1e49af0cea53f941c751b60532fdca4b2e94b3192221b3a1c915d2cf43aa9fd7ad"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 07:22:08.455069) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 07:22:08.455436) ========= 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=c6927cf182745ad25b1d38b9000001fe-1467036069.354982-1784195719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004372 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 07:22:08.537625) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 07:22:08.537995) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 07:22:08.538346) ========= ========= Total master_lag: 0.25 =========