builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1464622788.96 results: success (0) buildid: 20160530053004 builduid: e15448c5eb5545f1a0b00fdcc6fc0c62 revision: 00dccad34edae14b16de663c884a36ef2ec575b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:48.963433) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:48.963899) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:48.964170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:48.978843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:48.979142) ========= 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=4300f881736b422476174874000001bc-1464622788.508004-1165943564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-30 08:39:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-05-30 08:39:49 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.289611 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.282737) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.283093) ========= 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=4300f881736b422476174874000001bc-1464622788.508004-1165943564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021375 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.333550) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.333958) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 00dccad34edae14b16de663c884a36ef2ec575b4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 00dccad34edae14b16de663c884a36ef2ec575b4 --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=4300f881736b422476174874000001bc-1464622788.508004-1165943564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-30 08:39:49,655 truncating revision to first 12 chars 2016-05-30 08:39:49,655 Setting DEBUG logging. 2016-05-30 08:39:49,655 attempt 1/10 2016-05-30 08:39:49,655 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/00dccad34eda?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 08:39:49,980 unpacking tar archive at: fx-team-00dccad34eda/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647389 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.995453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.995849) ========= script_repo_revision: 00dccad34edae14b16de663c884a36ef2ec575b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.996324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:49.996626) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:39:50.006857) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-05-30 08:39:50.007196) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=4300f881736b422476174874000001bc-1464622788.508004-1165943564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:39:50 INFO - MultiFileLogger online at 20160530 08:39:50 in /builds/slave/test 08:39:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:39:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:39:50 INFO - {'append_to_log': False, 08:39:50 INFO - 'base_work_dir': '/builds/slave/test', 08:39:50 INFO - 'blob_upload_branch': 'Fx-Team', 08:39:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:39:50 INFO - 'branch': 'Fx-Team', 08:39:50 INFO - 'buildbot_json_path': 'buildprops.json', 08:39:50 INFO - 'config_files': ('talos/linux_config.py',), 08:39:50 INFO - 'default_actions': ('clobber', 08:39:50 INFO - 'read-buildbot-config', 08:39:50 INFO - 'download-and-extract', 08:39:50 INFO - 'populate-webroot', 08:39:50 INFO - 'create-virtualenv', 08:39:50 INFO - 'install', 08:39:50 INFO - 'run-tests'), 08:39:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:39:50 INFO - 'download_minidump_stackwalk': True, 08:39:50 INFO - 'download_symbols': 'ondemand', 08:39:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:39:50 INFO - 'tooltool.py': '/tools/tooltool.py', 08:39:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:39:50 INFO - '/tools/misc-python/virtualenv.py')}, 08:39:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:39:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:39:50 INFO - 'installer_path': 'installer.exe', 08:39:50 INFO - 'log_level': 'info', 08:39:50 INFO - 'log_name': 'talos', 08:39:50 INFO - 'log_to_console': True, 08:39:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:39:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:39:50 INFO - 'opt_config_files': (), 08:39:50 INFO - 'pip_index': False, 08:39:50 INFO - 'sps_profile': False, 08:39:50 INFO - 'sps_profile_interval': 0, 08:39:50 INFO - 'suite': 'dromaeojs', 08:39:50 INFO - 'system_bits': '32', 08:39:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:39:50 INFO - 'title': 'talos-linux64-ix-045', 08:39:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:39:50 INFO - 'use_talos_json': True, 08:39:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:39:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:39:50 INFO - 'work_dir': 'build'} 08:39:50 INFO - ##### 08:39:50 INFO - ##### Running clobber step. 08:39:50 INFO - ##### 08:39:50 INFO - Running pre-action listener: _resource_record_pre_action 08:39:50 INFO - Running main action method: clobber 08:39:50 INFO - rmtree: /builds/slave/test/build 08:39:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:39:50 INFO - Running post-action listener: _resource_record_post_action 08:39:50 INFO - ##### 08:39:50 INFO - ##### Finished clobber step (success) 08:39:50 INFO - ##### 08:39:50 INFO - ##### 08:39:50 INFO - ##### Running read-buildbot-config step. 08:39:50 INFO - ##### 08:39:50 INFO - Running pre-action listener: _resource_record_pre_action 08:39:50 INFO - Running main action method: read_buildbot_config 08:39:50 INFO - Using buildbot properties: 08:39:50 INFO - { 08:39:50 INFO - "project": "", 08:39:50 INFO - "product": "firefox", 08:39:50 INFO - "script_repo_revision": "production", 08:39:50 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:39:50 INFO - "repository": "", 08:39:50 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:39:50 INFO - "buildid": "20160530053004", 08:39:50 INFO - "pgo_build": "True", 08:39:50 INFO - "basedir": "/builds/slave/test", 08:39:50 INFO - "buildnumber": 1203, 08:39:50 INFO - "slavename": "talos-linux64-ix-045", 08:39:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:39:50 INFO - "platform": "ubuntu64_hw", 08:39:50 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:39:50 INFO - "branch": "fx-team", 08:39:50 INFO - "repo_path": "integration/fx-team", 08:39:50 INFO - "revision": "00dccad34edae14b16de663c884a36ef2ec575b4", 08:39:50 INFO - "stage_platform": "linux64-pgo", 08:39:50 INFO - "builduid": "e15448c5eb5545f1a0b00fdcc6fc0c62", 08:39:50 INFO - "slavebuilddir": "test" 08:39:50 INFO - } 08:39:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:39:50 INFO - Running post-action listener: _resource_record_post_action 08:39:50 INFO - ##### 08:39:50 INFO - ##### Finished read-buildbot-config step (success) 08:39:50 INFO - ##### 08:39:50 INFO - ##### 08:39:50 INFO - ##### Running download-and-extract step. 08:39:50 INFO - ##### 08:39:50 INFO - Running pre-action listener: _resource_record_pre_action 08:39:50 INFO - Running main action method: download_and_extract 08:39:50 INFO - mkdir: /builds/slave/test/build/tests 08:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:50 INFO - https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:39:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:39:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:39:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:39:51 INFO - Downloaded 1361 bytes. 08:39:51 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:39:51 INFO - Using the following test package requirements: 08:39:51 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:39:51 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:39:51 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:39:51 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'jsshell-linux-x86_64.zip'], 08:39:51 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:39:51 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:39:51 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:39:51 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:39:51 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:39:51 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:39:51 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:39:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:39: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')]} 08:39:51 INFO - https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:39:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:39:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:39:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:39:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:39:52 INFO - Downloaded 24411182 bytes. 08:39:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:39:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:39:53 INFO - Return code: 0 08:39:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:39:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:39:53 INFO - https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:39:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:39:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:39:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:39:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:39:55 INFO - Downloaded 24411182 bytes. 08:39:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:39:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:39:56 INFO - Return code: 0 08:39:56 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')]} 08:39:56 INFO - https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:39:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:39:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:39:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:39:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:39:57 INFO - Downloaded 10834034 bytes. 08:39:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:39:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:39:58 INFO - Return code: 0 08:39:58 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')]} 08:39:58 INFO - https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:39:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:39:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:39:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:39:59 INFO - Downloaded 58761087 bytes. 08:39:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:39:59 INFO - mkdir: /builds/slave/test/properties 08:39:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:39:59 INFO - Writing to file /builds/slave/test/properties/build_url 08:39:59 INFO - Contents: 08:39:59 INFO - build_url:https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:40:00 INFO - Running post-action listener: _resource_record_post_action 08:40:00 INFO - Running post-action listener: set_extra_try_arguments 08:40:00 INFO - ##### 08:40:00 INFO - ##### Finished download-and-extract step (success) 08:40:00 INFO - ##### 08:40:00 INFO - ##### 08:40:00 INFO - ##### Running populate-webroot step. 08:40:00 INFO - ##### 08:40:00 INFO - Running pre-action listener: _resource_record_pre_action 08:40:00 INFO - Running main action method: populate_webroot 08:40:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:40:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:40:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:40:00 INFO - '--tppagecycles', 08:40:00 INFO - '7'], 08:40:00 INFO - 'tests': ['tsvgm']}}, 08:40:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:40:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:40:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:40:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:40:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:40:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:40:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['damp', 'tps']}, 08:40:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'tests': ['damp', 'tps']}, 08:40:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['dromaeo_dom']}, 08:40:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:40:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['a11yr', 08:40:00 INFO - 'ts_paint', 08:40:00 INFO - 'tpaint', 08:40:00 INFO - 'sessionrestore', 08:40:00 INFO - 'sessionrestore_no_auto_restore', 08:40:00 INFO - 'tabpaint']}, 08:40:00 INFO - 'other-e10s': {'tests': ['a11yr', 08:40:00 INFO - 'ts_paint', 08:40:00 INFO - 'tpaint', 08:40:00 INFO - 'sessionrestore', 08:40:00 INFO - 'sessionrestore_no_auto_restore', 08:40:00 INFO - 'tabpaint']}, 08:40:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:40:00 INFO - 'ts_paint', 08:40:00 INFO - 'tpaint', 08:40:00 INFO - 'sessionrestore', 08:40:00 INFO - 'sessionrestore_no_auto_restore', 08:40:00 INFO - 'tabpaint']}, 08:40:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:40:00 INFO - 'ts_paint', 08:40:00 INFO - 'tpaint', 08:40:00 INFO - 'sessionrestore', 08:40:00 INFO - 'sessionrestore_no_auto_restore', 08:40:00 INFO - 'tabpaint']}, 08:40:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['a11yr', 08:40:00 INFO - 'ts_paint', 08:40:00 INFO - 'tpaint', 08:40:00 INFO - 'sessionrestore', 08:40:00 INFO - 'sessionrestore_no_auto_restore', 08:40:00 INFO - 'tabpaint']}, 08:40:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['a11yr', 08:40:00 INFO - 'ts_paint', 08:40:00 INFO - 'tpaint', 08:40:00 INFO - 'sessionrestore', 08:40:00 INFO - 'sessionrestore_no_auto_restore', 08:40:00 INFO - 'tabpaint']}, 08:40:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['tsvgx', 08:40:00 INFO - 'tsvgr_opacity', 08:40:00 INFO - 'tart', 08:40:00 INFO - 'tscrollx', 08:40:00 INFO - 'cart']}, 08:40:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:40:00 INFO - 'tsvgr_opacity', 08:40:00 INFO - 'tart', 08:40:00 INFO - 'tscrollx', 08:40:00 INFO - 'cart']}, 08:40:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'talos_options': ['--disable-e10s'], 08:40:00 INFO - 'tests': ['tp5o']}, 08:40:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'tests': ['tp5o']}, 08:40:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'talos_options': ['--disable-e10s', 08:40:00 INFO - '--xperf_path', 08:40:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:40:00 INFO - 'tests': ['tp5n']}, 08:40:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:40:00 INFO - 'talos_options': ['--xperf_path', 08:40:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:40:00 INFO - 'tests': ['tp5n']}}, 08:40:00 INFO - 'talos.zip': {'path': '', 08:40:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:40:00 INFO - Running post-action listener: _resource_record_post_action 08:40:00 INFO - ##### 08:40:00 INFO - ##### Finished populate-webroot step (success) 08:40:00 INFO - ##### 08:40:00 INFO - ##### 08:40:00 INFO - ##### Running create-virtualenv step. 08:40:00 INFO - ##### 08:40:00 INFO - Running pre-action listener: _resource_record_pre_action 08:40:00 INFO - Running main action method: create_virtualenv 08:40:00 INFO - Creating virtualenv /builds/slave/test/build/venv 08:40:00 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 08:40:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:40:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:40:00 INFO - Using real prefix '/usr' 08:40:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:40:01 INFO - Installing distribute.............................................................................................................................................................................................done. 08:40:03 INFO - Installing pip.................done. 08:40:03 INFO - Return code: 0 08:40:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:40:03 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')]} 08:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:03 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')]} 08:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:03 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:03 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 08:40:03 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 08:40:03 INFO - Using env: {'DISPLAY': ':0', 08:40:03 INFO - 'HOME': '/home/cltbld', 08:40:03 INFO - 'LANG': 'en_US.UTF-8', 08:40:03 INFO - 'LANGUAGE': 'en_US:en', 08:40:03 INFO - 'LOGNAME': 'cltbld', 08:40:03 INFO - 'MAIL': '/var/mail/cltbld', 08:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:03 INFO - 'MOZ_NO_REMOTE': '1', 08:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:03 INFO - 'NO_EM_RESTART': '1', 08:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:03 INFO - 'PWD': '/builds/slave/test', 08:40:03 INFO - 'SHELL': '/bin/bash', 08:40:03 INFO - 'SHLVL': '1', 08:40:03 INFO - 'TERM': 'linux', 08:40:03 INFO - 'TMOUT': '86400', 08:40:03 INFO - 'USER': 'cltbld', 08:40:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:03 INFO - '_': '/tools/buildbot/bin/python'} 08:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:03 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 08:40:03 INFO - Cleaning up... 08:40:03 INFO - Return code: 0 08:40:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:40:03 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')]} 08:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:03 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')]} 08:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:03 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:03 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 08:40:03 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 08:40:03 INFO - Using env: {'DISPLAY': ':0', 08:40:03 INFO - 'HOME': '/home/cltbld', 08:40:03 INFO - 'LANG': 'en_US.UTF-8', 08:40:03 INFO - 'LANGUAGE': 'en_US:en', 08:40:03 INFO - 'LOGNAME': 'cltbld', 08:40:03 INFO - 'MAIL': '/var/mail/cltbld', 08:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:03 INFO - 'MOZ_NO_REMOTE': '1', 08:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:03 INFO - 'NO_EM_RESTART': '1', 08:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:03 INFO - 'PWD': '/builds/slave/test', 08:40:03 INFO - 'SHELL': '/bin/bash', 08:40:03 INFO - 'SHLVL': '1', 08:40:03 INFO - 'TERM': 'linux', 08:40:03 INFO - 'TMOUT': '86400', 08:40:03 INFO - 'USER': 'cltbld', 08:40:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:03 INFO - '_': '/tools/buildbot/bin/python'} 08:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:03 INFO - Downloading/unpacking psutil>=3.1.1 08:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:40:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:40:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:40:05 INFO - Installing collected packages: psutil 08:40:05 INFO - Running setup.py install for psutil 08:40:05 INFO - building 'psutil._psutil_linux' extension 08:40:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:40:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:40:05 INFO - building 'psutil._psutil_posix' extension 08:40:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:40:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:40:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:40:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:40:05 INFO - Successfully installed psutil 08:40:05 INFO - Cleaning up... 08:40:06 INFO - Return code: 0 08:40:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:06 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:06 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 08:40:06 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 08:40:06 INFO - Using env: {'DISPLAY': ':0', 08:40:06 INFO - 'HOME': '/home/cltbld', 08:40:06 INFO - 'LANG': 'en_US.UTF-8', 08:40:06 INFO - 'LANGUAGE': 'en_US:en', 08:40:06 INFO - 'LOGNAME': 'cltbld', 08:40:06 INFO - 'MAIL': '/var/mail/cltbld', 08:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:06 INFO - 'MOZ_NO_REMOTE': '1', 08:40:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:06 INFO - 'NO_EM_RESTART': '1', 08:40:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:06 INFO - 'PWD': '/builds/slave/test', 08:40:06 INFO - 'SHELL': '/bin/bash', 08:40:06 INFO - 'SHLVL': '1', 08:40:06 INFO - 'TERM': 'linux', 08:40:06 INFO - 'TMOUT': '86400', 08:40:06 INFO - 'USER': 'cltbld', 08:40:06 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:06 INFO - '_': '/tools/buildbot/bin/python'} 08:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:40:06 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 08:40:06 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 08:40:06 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 08:40:06 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 08:40:06 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 08:40:06 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 08:40:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:40:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:40:08 INFO - Installing collected packages: mozsystemmonitor 08:40:08 INFO - Running setup.py install for mozsystemmonitor 08:40:08 INFO - Successfully installed mozsystemmonitor 08:40:08 INFO - Cleaning up... 08:40:08 INFO - Return code: 0 08:40:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:08 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:08 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 08:40:08 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 08:40:08 INFO - Using env: {'DISPLAY': ':0', 08:40:08 INFO - 'HOME': '/home/cltbld', 08:40:08 INFO - 'LANG': 'en_US.UTF-8', 08:40:08 INFO - 'LANGUAGE': 'en_US:en', 08:40:08 INFO - 'LOGNAME': 'cltbld', 08:40:08 INFO - 'MAIL': '/var/mail/cltbld', 08:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:08 INFO - 'MOZ_NO_REMOTE': '1', 08:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:08 INFO - 'NO_EM_RESTART': '1', 08:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:08 INFO - 'PWD': '/builds/slave/test', 08:40:08 INFO - 'SHELL': '/bin/bash', 08:40:08 INFO - 'SHLVL': '1', 08:40:08 INFO - 'TERM': 'linux', 08:40:08 INFO - 'TMOUT': '86400', 08:40:08 INFO - 'USER': 'cltbld', 08:40:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:08 INFO - '_': '/tools/buildbot/bin/python'} 08:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:08 INFO - Downloading/unpacking blobuploader==1.2.4 08:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:40:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:40:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:11 INFO - Downloading docopt-0.6.1.tar.gz 08:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:40:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:40:11 INFO - Running setup.py install for blobuploader 08:40:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:40:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:40:11 INFO - Running setup.py install for requests 08:40:11 INFO - Running setup.py install for docopt 08:40:11 INFO - Successfully installed blobuploader requests docopt 08:40:11 INFO - Cleaning up... 08:40:11 INFO - Return code: 0 08:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:11 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')]} 08:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:11 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')]} 08:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:11 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:11 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 08:40:11 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 08:40:11 INFO - Using env: {'DISPLAY': ':0', 08:40:11 INFO - 'HOME': '/home/cltbld', 08:40:11 INFO - 'LANG': 'en_US.UTF-8', 08:40:11 INFO - 'LANGUAGE': 'en_US:en', 08:40:11 INFO - 'LOGNAME': 'cltbld', 08:40:11 INFO - 'MAIL': '/var/mail/cltbld', 08:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:11 INFO - 'MOZ_NO_REMOTE': '1', 08:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:11 INFO - 'NO_EM_RESTART': '1', 08:40:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:11 INFO - 'PWD': '/builds/slave/test', 08:40:11 INFO - 'SHELL': '/bin/bash', 08:40:11 INFO - 'SHLVL': '1', 08:40:11 INFO - 'TERM': 'linux', 08:40:11 INFO - 'TMOUT': '86400', 08:40:11 INFO - 'USER': 'cltbld', 08:40:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:11 INFO - '_': '/tools/buildbot/bin/python'} 08:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:11 INFO - Running setup.py (path:/tmp/pip-_8IBTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:12 INFO - Running setup.py (path:/tmp/pip-QCc9Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:12 INFO - Running setup.py (path:/tmp/pip-B8pO2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:12 INFO - Running setup.py (path:/tmp/pip-lo7Wrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:12 INFO - Running setup.py (path:/tmp/pip-5Et405-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:12 INFO - Running setup.py (path:/tmp/pip-t_mdpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:12 INFO - Running setup.py (path:/tmp/pip-CWfynP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:12 INFO - Running setup.py (path:/tmp/pip-NG4L0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:12 INFO - Running setup.py (path:/tmp/pip-AFo25Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:12 INFO - Running setup.py (path:/tmp/pip-HoMvKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:12 INFO - Running setup.py (path:/tmp/pip-pcfhAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:12 INFO - Running setup.py (path:/tmp/pip-0YUtlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:12 INFO - Running setup.py (path:/tmp/pip-UPeryI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:12 INFO - Running setup.py (path:/tmp/pip-y1E49k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:12 INFO - Running setup.py (path:/tmp/pip-8v1fAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:13 INFO - Running setup.py (path:/tmp/pip-rS3HY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:13 INFO - Running setup.py (path:/tmp/pip-6IxP7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:40:13 INFO - Running setup.py install for manifestparser 08:40:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Running setup.py install for mozcrash 08:40:13 INFO - Running setup.py install for mozdebug 08:40:13 INFO - Running setup.py install for mozdevice 08:40:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Running setup.py install for mozfile 08:40:13 INFO - Running setup.py install for mozhttpd 08:40:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Running setup.py install for mozinfo 08:40:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Running setup.py install for mozInstall 08:40:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:40:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Running setup.py install for mozleak 08:40:14 INFO - Running setup.py install for mozlog 08:40:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Running setup.py install for moznetwork 08:40:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Running setup.py install for mozprocess 08:40:14 INFO - Running setup.py install for mozprofile 08:40:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Running setup.py install for mozrunner 08:40:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Running setup.py install for mozscreenshot 08:40:14 INFO - Running setup.py install for moztest 08:40:14 INFO - Running setup.py install for mozversion 08:40:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:40:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:40:14 INFO - Cleaning up... 08:40:14 INFO - Return code: 0 08:40:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:14 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:14 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 08:40:14 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 08:40:14 INFO - Using env: {'DISPLAY': ':0', 08:40:14 INFO - 'HOME': '/home/cltbld', 08:40:14 INFO - 'LANG': 'en_US.UTF-8', 08:40:14 INFO - 'LANGUAGE': 'en_US:en', 08:40:14 INFO - 'LOGNAME': 'cltbld', 08:40:14 INFO - 'MAIL': '/var/mail/cltbld', 08:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:14 INFO - 'MOZ_NO_REMOTE': '1', 08:40:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:14 INFO - 'NO_EM_RESTART': '1', 08:40:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:14 INFO - 'PWD': '/builds/slave/test', 08:40:14 INFO - 'SHELL': '/bin/bash', 08:40:14 INFO - 'SHLVL': '1', 08:40:14 INFO - 'TERM': 'linux', 08:40:14 INFO - 'TMOUT': '86400', 08:40:14 INFO - 'USER': 'cltbld', 08:40:14 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:14 INFO - '_': '/tools/buildbot/bin/python'} 08:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:15 INFO - Running setup.py (path:/tmp/pip-XiCsI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:15 INFO - Running setup.py (path:/tmp/pip-E5pB_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:15 INFO - Running setup.py (path:/tmp/pip-2SDu7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:15 INFO - Running setup.py (path:/tmp/pip-YPAT_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:15 INFO - Running setup.py (path:/tmp/pip-CQtJ02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:15 INFO - Running setup.py (path:/tmp/pip-cJsfGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:15 INFO - Running setup.py (path:/tmp/pip-wSfcvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:15 INFO - Running setup.py (path:/tmp/pip-dL0m2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:15 INFO - Running setup.py (path:/tmp/pip-Hbtu6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:15 INFO - Running setup.py (path:/tmp/pip-mFoQz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:15 INFO - Running setup.py (path:/tmp/pip-UYNbMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:15 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)) 08:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:15 INFO - Running setup.py (path:/tmp/pip-djaEWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:16 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)) 08:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:16 INFO - Running setup.py (path:/tmp/pip-7u29P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:16 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)) 08:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:16 INFO - Running setup.py (path:/tmp/pip-WRg2yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:16 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)) 08:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:16 INFO - Running setup.py (path:/tmp/pip-c3NwUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:16 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)) 08:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:16 INFO - Running setup.py (path:/tmp/pip-p4TCzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:16 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)) 08:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:16 INFO - Running setup.py (path:/tmp/pip-yt_wh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:16 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)) 08:40:16 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)) 08:40:16 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)) 08:40:16 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)) 08:40:16 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)) 08:40:16 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)) 08:40:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:40:16 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 08:40:16 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 08:40:16 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 08:40:16 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 08:40:16 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 08:40:16 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 08:40:18 INFO - Downloading blessings-1.6.tar.gz 08:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:40:18 INFO - Installing collected packages: blessings 08:40:18 INFO - Running setup.py install for blessings 08:40:18 INFO - Successfully installed blessings 08:40:18 INFO - Cleaning up... 08:40:18 INFO - Return code: 0 08:40:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:40:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:18 INFO - Reading from file tmpfile_stdout 08:40:18 INFO - Current package versions: 08:40:18 INFO - argparse == 1.2.1 08:40:18 INFO - blessings == 1.6 08:40:18 INFO - blobuploader == 1.2.4 08:40:18 INFO - docopt == 0.6.1 08:40:18 INFO - manifestparser == 1.1 08:40:18 INFO - mozInstall == 1.12 08:40:18 INFO - mozcrash == 0.17 08:40:18 INFO - mozdebug == 0.1 08:40:18 INFO - mozdevice == 0.48 08:40:18 INFO - mozfile == 1.2 08:40:18 INFO - mozhttpd == 0.7 08:40:18 INFO - mozinfo == 0.9 08:40:18 INFO - mozleak == 0.1 08:40:18 INFO - mozlog == 3.2 08:40:18 INFO - moznetwork == 0.27 08:40:18 INFO - mozprocess == 0.22 08:40:18 INFO - mozprofile == 0.28 08:40:18 INFO - mozrunner == 6.11 08:40:18 INFO - mozscreenshot == 0.1 08:40:18 INFO - mozsystemmonitor == 0.3 08:40:18 INFO - moztest == 0.7 08:40:18 INFO - mozversion == 1.4 08:40:18 INFO - psutil == 3.1.1 08:40:18 INFO - requests == 1.2.3 08:40:18 INFO - wsgiref == 0.1.2 08:40:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:18 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')]} 08:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:18 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')]} 08:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:18 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:18 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 08:40:18 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 08:40:18 INFO - Using env: {'DISPLAY': ':0', 08:40:18 INFO - 'HOME': '/home/cltbld', 08:40:18 INFO - 'LANG': 'en_US.UTF-8', 08:40:18 INFO - 'LANGUAGE': 'en_US:en', 08:40:18 INFO - 'LOGNAME': 'cltbld', 08:40:18 INFO - 'MAIL': '/var/mail/cltbld', 08:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:18 INFO - 'MOZ_NO_REMOTE': '1', 08:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:18 INFO - 'NO_EM_RESTART': '1', 08:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:18 INFO - 'PWD': '/builds/slave/test', 08:40:18 INFO - 'SHELL': '/bin/bash', 08:40:18 INFO - 'SHLVL': '1', 08:40:18 INFO - 'TERM': 'linux', 08:40:18 INFO - 'TMOUT': '86400', 08:40:18 INFO - 'USER': 'cltbld', 08:40:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:18 INFO - '_': '/tools/buildbot/bin/python'} 08:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 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)) 08:40:18 INFO - Cleaning up... 08:40:18 INFO - Return code: 0 08:40:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:40:18 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')]} 08:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:18 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')]} 08:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:18 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 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:18 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 08:40:18 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 08:40:18 INFO - Using env: {'DISPLAY': ':0', 08:40:18 INFO - 'HOME': '/home/cltbld', 08:40:18 INFO - 'LANG': 'en_US.UTF-8', 08:40:18 INFO - 'LANGUAGE': 'en_US:en', 08:40:18 INFO - 'LOGNAME': 'cltbld', 08:40:18 INFO - 'MAIL': '/var/mail/cltbld', 08:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:18 INFO - 'MOZ_NO_REMOTE': '1', 08:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:18 INFO - 'NO_EM_RESTART': '1', 08:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:18 INFO - 'PWD': '/builds/slave/test', 08:40:18 INFO - 'SHELL': '/bin/bash', 08:40:18 INFO - 'SHLVL': '1', 08:40:18 INFO - 'TERM': 'linux', 08:40:18 INFO - 'TMOUT': '86400', 08:40:18 INFO - 'USER': 'cltbld', 08:40:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:18 INFO - '_': '/tools/buildbot/bin/python'} 08:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:19 INFO - Downloading/unpacking jsonschema 08:40:19 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 08:40:19 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 08:40:19 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 08:40:19 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 08:40:19 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 08:40:19 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 08:40:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:40:21 INFO - Downloading/unpacking functools32 (from jsonschema) 08:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:40:21 INFO - warning: no files found matching '*.txt' 08:40:21 INFO - no previously-included directories found matching 'build' 08:40:21 INFO - no previously-included directories found matching 'dist' 08:40:21 INFO - no previously-included directories found matching '.git*' 08:40:21 INFO - Installing collected packages: jsonschema, functools32 08:40:21 INFO - Running setup.py install for functools32 08:40:21 INFO - warning: no files found matching '*.txt' 08:40:21 INFO - no previously-included directories found matching 'build' 08:40:21 INFO - no previously-included directories found matching 'dist' 08:40:21 INFO - no previously-included directories found matching '.git*' 08:40:21 INFO - Successfully installed jsonschema functools32 08:40:21 INFO - Cleaning up... 08:40:21 INFO - Return code: 0 08:40:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0ea32f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1893770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, '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': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', '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 08:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:40:21 INFO - Using env: {'DISPLAY': ':0', 08:40:21 INFO - 'HOME': '/home/cltbld', 08:40:21 INFO - 'LANG': 'en_US.UTF-8', 08:40:21 INFO - 'LANGUAGE': 'en_US:en', 08:40:21 INFO - 'LOGNAME': 'cltbld', 08:40:21 INFO - 'MAIL': '/var/mail/cltbld', 08:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:21 INFO - 'MOZ_NO_REMOTE': '1', 08:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:21 INFO - 'NO_EM_RESTART': '1', 08:40:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:21 INFO - 'PWD': '/builds/slave/test', 08:40:21 INFO - 'SHELL': '/bin/bash', 08:40:21 INFO - 'SHLVL': '1', 08:40:21 INFO - 'TERM': 'linux', 08:40:21 INFO - 'TMOUT': '86400', 08:40:21 INFO - 'USER': 'cltbld', 08:40:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:21 INFO - '_': '/tools/buildbot/bin/python'} 08:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:21 INFO - Downloading/unpacking flake8 08:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:40:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:40:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:40:24 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 08:40:24 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 08:40:24 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 08:40:24 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 08:40:24 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 08:40:24 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 08:40:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:40:24 INFO - no previously-included directories found matching 'docs/_build' 08:40:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:40:24 INFO - Running setup.py install for pep8 08:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:40:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:40:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:40:24 INFO - no previously-included directories found matching 'docs/_build' 08:40:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:40:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:40:24 INFO - Cleaning up... 08:40:24 INFO - Return code: 0 08:40:24 INFO - Running post-action listener: _resource_record_post_action 08:40:24 INFO - Running post-action listener: _start_resource_monitoring 08:40:24 INFO - Starting resource monitoring. 08:40:24 INFO - ##### 08:40:24 INFO - ##### Finished create-virtualenv step (success) 08:40:24 INFO - ##### 08:40:24 INFO - ##### 08:40:24 INFO - ##### Running install step. 08:40:24 INFO - ##### 08:40:24 INFO - Running pre-action listener: _resource_record_pre_action 08:40:24 INFO - Running main action method: install 08:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:24 INFO - Reading from file tmpfile_stdout 08:40:24 INFO - Detecting whether we're running mozinstall >=1.0... 08:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:40:24 INFO - Reading from file tmpfile_stdout 08:40:24 INFO - Output received: 08:40:24 INFO - Usage: mozinstall [options] installer 08:40:24 INFO - Options: 08:40:24 INFO - -h, --help show this help message and exit 08:40:24 INFO - -d DEST, --destination=DEST 08:40:24 INFO - Directory to install application into. [default: 08:40:24 INFO - "/builds/slave/test"] 08:40:24 INFO - --app=APP Application being installed. [default: firefox] 08:40:24 INFO - mkdir: /builds/slave/test/build/application 08:40:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:40:41 INFO - Reading from file tmpfile_stdout 08:40:41 INFO - Output received: 08:40:41 INFO - /builds/slave/test/build/application/firefox/firefox 08:40:41 INFO - Running post-action listener: _resource_record_post_action 08:40:41 INFO - ##### 08:40:41 INFO - ##### Finished install step (success) 08:40:41 INFO - ##### 08:40:41 INFO - ##### 08:40:41 INFO - ##### Running run-tests step. 08:40:41 INFO - ##### 08:40:41 INFO - Running pre-action listener: _resource_record_pre_action 08:40:41 INFO - Running main action method: run_tests 08:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:40:41 INFO - Python 2.7.3 08:40:41 INFO - Return code: 0 08:40:41 INFO - grabbing minidump binary from tooltool 08:40: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')]} 08:40:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19229c0>, '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 0x19238c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1923d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:40:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:40:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:40:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:40:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:40:41 INFO - Return code: 0 08:40:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:40:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:40:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:40:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:40:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:40:42 INFO - Return code: 0 08:40:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 08:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 08:40:42 INFO - Using env: {'DISPLAY': ':0', 08:40:42 INFO - 'HOME': '/home/cltbld', 08:40:42 INFO - 'LANG': 'en_US.UTF-8', 08:40:42 INFO - 'LANGUAGE': 'en_US:en', 08:40:42 INFO - 'LOGNAME': 'cltbld', 08:40:42 INFO - 'MAIL': '/var/mail/cltbld', 08:40:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:40:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:42 INFO - 'MOZ_NO_REMOTE': '1', 08:40:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:40:42 INFO - 'NO_EM_RESTART': '1', 08:40:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:42 INFO - 'PWD': '/builds/slave/test', 08:40:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:40:42 INFO - 'SHELL': '/bin/bash', 08:40:42 INFO - 'SHLVL': '1', 08:40:42 INFO - 'TERM': 'linux', 08:40:42 INFO - 'TMOUT': '86400', 08:40:42 INFO - 'USER': 'cltbld', 08:40:42 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464622788.508004-1165943564', 08:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:42 INFO - '_': '/tools/buildbot/bin/python'} 08:40:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 08:40:42 INFO - mozversion application_buildid: 20160530053004 08:40:42 INFO - mozversion application_changeset: 00dccad34edae14b16de663c884a36ef2ec575b4 08:40:42 INFO - mozversion application_display_name: Nightly 08:40:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:40:42 INFO - mozversion application_name: Firefox 08:40:42 INFO - mozversion application_remotingname: firefox 08:40:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:40:42 INFO - mozversion application_vendor: Mozilla 08:40:42 INFO - mozversion application_version: 49.0a1 08:40:42 INFO - mozversion platform_buildid: 20160530053004 08:40:42 INFO - mozversion platform_changeset: 00dccad34edae14b16de663c884a36ef2ec575b4 08:40:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:40:42 INFO - mozversion platform_version: 49.0a1 08:40:42 INFO - using testdate: 1464622842 08:40:42 INFO - actual date: 1464622842 08:40:42 INFO - starting webserver on 'localhost:60689' 08:40:42 INFO - SUITE-START | Running 2 tests 08:40:42 INFO - TEST-START | dromaeo_css 08:40:42 INFO - operating with platform_type : linux_ 08:40:42 INFO - Initialising browser for dromaeo_css test... 08:40:42 INFO - TEST-INFO | started process 28220 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7ZSgHq/profile http://localhost:60689/getInfo.html) 08:40:46 INFO - PROCESS | 28220 | __metrics Screen width/height:1600/1200 08:40:46 INFO - PROCESS | 28220 | colorDepth:24 08:40:46 INFO - PROCESS | 28220 | Browser inner width/height: 1024/697 08:40:46 INFO - PROCESS | 28220 | __metrics 08:40:47 ERROR - PROCESS | 28220 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:40:48 INFO - TEST-INFO | 28220: exit 0 08:40:48 INFO - Browser initialized. 08:40:48 INFO - Running cycle 1/1 for dromaeo_css test... 08:40:48 INFO - TEST-INFO | started process 28303 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7ZSgHq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:00 INFO - PROCESS | 28303 | RSS: Main: 155668480 08:41:00 INFO - PROCESS | 28303 | 08:41:51 INFO - PROCESS | 28303 | 1464622911699 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] 08:43:59 INFO - PROCESS | 28303 | Cycle 1(1): loaded http://localhost:60689/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60689/tests/dromaeo/cssquery-ext.html) 08:44:00 INFO - PROCESS | 28303 | RSS: Main: 247746560 08:44:00 INFO - PROCESS | 28303 | 08:47:03 INFO - PROCESS | 28303 | Cycle 1(1): loaded http://localhost:60689/tests/dromaeo/cssquery-ext.html (next: http://localhost:60689/tests/dromaeo/cssquery-jquery.html) 08:47:03 INFO - PROCESS | 28303 | RSS: Main: 243081216 08:47:03 INFO - PROCESS | 28303 | 08:50:05 INFO - PROCESS | 28303 | Cycle 1(1): loaded http://localhost:60689/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60689/tests/dromaeo/cssquery-mootools.html) 08:50:06 INFO - PROCESS | 28303 | RSS: Main: 246046720 08:50:06 INFO - PROCESS | 28303 | 08:53:08 INFO - PROCESS | 28303 | Cycle 1(1): loaded http://localhost:60689/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60689/tests/dromaeo/cssquery-prototype.html) 08:53:09 INFO - PROCESS | 28303 | RSS: Main: 237047808 08:53:09 INFO - PROCESS | 28303 | 08:56:11 INFO - PROCESS | 28303 | Cycle 1(1): loaded http://localhost:60689/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60689/tests/dromaeo/cssquery-yui.html) 08:56:12 INFO - PROCESS | 28303 | RSS: Main: 214970368 08:56:12 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | Cycle 1(1): loaded http://localhost:60689/tests/dromaeo/cssquery-yui.html (next: http://localhost:60689/tests/dromaeo/cssquery-dojo.html) 08:59:15 INFO - PROCESS | 28303 | RSS: Main: 304349184 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | __start_tp_report 08:59:15 INFO - PROCESS | 28303 | _x_x_mozilla_page_load 08:59:15 INFO - PROCESS | 28303 | _x_x_mozilla_page_load_details 08:59:15 INFO - PROCESS | 28303 | |i|pagename|runs| 08:59:15 INFO - PROCESS | 28303 | |0;dojo.html;3099.628044634644;3137.8744850205994;3143.968560314397;3174.904752857415;3181.793183443076;5250.31745873036;5366;5382.757775900084;5490.780368785248;5493.45065493451;5478.233047373371;5510.972445137769;5523.7790488380415;5560.277163968689;5564.86087847804;4932.654714170009;4936.901261974767;5114.105031619451;5170.121079416508;5195.376554813428;5082.110630639641;5082.898342033157;5138.100832354342;5156.69059856408;5156.974215128919;4811.254255590372;4945.653804233705;4989.126902792033;4998.525140111684;5007.649464537484;5017.623678224148;5019.3976722793495;5020;5026.195808670595;5069.949300506984;4981.352424184833;5012.122878496249;5022.547970682656;5039.3196918415915;5086.949130508685;5230.581553475713;5310.336207974004;5315.867103322409;5355.384130824966;5358.517733403974;5751.626144300607;5793.391693872167;5825.766969321222;5869.4717475427415;5880.852978675524;5754.79858204965;5876.6180198286975;5917.792877249319;5921.792737254175;5928.911066333965;5380.3274590676165;5433.864153396157;5443.537299329561;5525.613207075467;5529.30883639545;6023.457888789942;6046.244219472566;6049.576529642883;6053.4551890330085;6060.727267272983;5603.663780173202;5662.320521537359;5805.187273781674;5811.622244554091;5817.505512031525;5761.452661997104;5824.213731146326;5867;5868.178455016301;5869.20765696634;5810.854728631818;5816.883662326814;5820.679862607597;5823.505002074787;5823.56323275756;5482.91775623366;5746.080627099644;5823;5830.387809279964;5834.970825145932;4457.398251236042;4459.175052615276;4467;4498.50516443191;4499.212637788374;3961.3661814109605;3979.303621866162;3981.203759248162;4002.4996875390575;4015.0966032642423;5333.493318134771;5355.036093503207;5485.259489968803;5534.889302213933;5599.300087489064;5856.092305692625;5864.472197502245;5882.588218824641;5885.411458854081;5928.614640048383;5562.527185189304;5582.692951887604;5582.804601838916;5585.078462053716;5588.189712491747;4907.607391408679;5059.241113832881;5101.617378696538;5113.411957624847;5118.846434606968;6189.071639254058;6192.287886892975;6193.102000210034;6196.442320191295;6197.318294987638;5710.600257981901;5959.851003724942;5968.910466342922;5973;5977.432143946318;6020.548458865515;6033.276006879202;6068.180795592715;6100.73256437228;6107.6671455628975;5716.942830571641;5730.398308177581;5760.798372056958;5782.739776710142;5829.329627092939;5801.680907549957;5871.882562348815;5873.471387575139;5875.236219291635;5875.618084824644;5513.255710479034;5796.072628379439;5817.447342502455;5839.35767065614;5889.676067816311;5789.710514474175;5790.392008839011;5794.82615521535;5794.855128621818;5806.68063256525;5446.074167391474;5706.828795136153;5713.885722285448;5721.485066343883;5722.198892155018;5682.829515114553;5736.741846616996;5741.339745929272;5805.535557155333;5820.796272130455;5609.635373700697;5644.520215781705;5646.971765141148;5648.519875810603;5652.236948011966 08:59:15 INFO - PROCESS | 28303 | |1;ext.html;625.1966223402928;628.9402506761858;637.356270167131;638.307436431472;647.1425361396151;42311.36532952004;43840.561594384046;43871.78064109679;48342.27486587704;48387;45485;46152;46196;46257.53742462574;46288.76855615726;8693.391462597636;8867.024627290994;8867.334949878752;8889.555522223895;8894.28845692343;8761.123887611251;8850.468971861677;8852.5573721314;8857.291416686681;8860.955695221515;8821.558922053904;8837.73486795394;8876.245519130882;8878.156575125386;8884.11158884113;8841;8892;8894.199522043014;8896.911030889738;8897.911020889773;8514.48913065218;8563.871541926876;8573.056963733985;8574.742757717278;8616.095309992486;42307.36538951918;42600.78699606513;42736.35895461571;42741.57258427407;42750.786246068885;3525.2420729543014;3537.6108628050893;3662.9084272893124;3683.318586061587;3940.54683711374;32490.350192996008;32815.34369312624;33369.83315083433;33760;34082.31835363304;4329.9134017319475;4382.715123516993;4405.559444055601;4416.6025057744955;4436.26587834989;343266;343294;343313.28343358374;343881;345693;57125.42874571201;57295.14057289144;57510.42489575134;57672.71163644155;57710.134347984815;52255;57282.140767888515;58781.41218587845;59384.40615593875;64687.353126469076;14729.705405891822;14810.111393316436;14875.70248595022;14878.181700006387;14879.851201487847;11366.659000230007;11456.828147577793;11460.94269528647;11472.655820325404;11477.196596238184;14218.28908554568;15844.28700708471;15904.602384940237;16232.594185145466;16654;6135.417135372132;6194.946859033975;6264.467520260829;6384.361563843671;6390.7443702251385;872.437277955715;1009.1623952119724;1023.0919335439501;1033.7105610429091;1036.4403222260046;32895.506567401506;32925.17687057843;32997.50503742446;33248.66751332504;33411.83294083514;1112.3993043756282;1117.1230583991528;1123.1071298317856;1125.58915995661;1167.871534131245;16530.504084877473;17089.743653845202;17175.57061073483;17241.65516689659;17378.739318910226;151079;151695;152636;153066;153279;59256.70371648114;59350.40649593535;59429.70285148547;59489.10766338508;59497.702511487165;1262.299423819789;1371.1019282370064;1385.8267716535433;1392.0812263905773;1403.8034675145489;32003;33373.499397508574;33776.10246514291;33802;34247.143821404665;49638.75180624074;50015.49984500254;50037.24944125914;50091.74954125206;50093.24860127174;418249;420229;420712;421503;421933;31151.071129586366;35516.46725299071;35639.28721425505;38484.615153848106;38603.80698096604;47385.52614473949;47996.760016199696;48496.757516212194;49400.25899611581;49431.50568494269;39804.60195398125;40257.79871100743;41271;41372.172556548096;42172.156556868074;40335.19329613333;40839.795801021995;40923.38614920838;40926.181476370904;41657.1668566633;39457.605423945395;40791.7960410196;41077.79461102675;41186.17627647371;41683.37474937939;42295.57704422916;43493.78253108841;43526.56473435352;46648.533514665774;47182.29226561535;32152.678473215903;33252;35283.11792205215;35457.290854182254;35926.101847454025 08:59:15 INFO - PROCESS | 28303 | |2;jquery.html;395.673569305323;403.58632401788145;406.83319838866066;412.7771003658023;426.38174646762184;13034.543790967318;17942.73085903711;19194.136263868124;19329.226830926764;19374.321898733546;18319.358822441216;18614.53463663409;18778;19107.426777196673;19123.23507059716;5676.290463692048;5739.339975902762;5789.565782566325;5808.506276966463;5849.561282903778;5679.176519404684;5722.599418040739;5731.168980497825;5735.369109397963;5766.913496297538;4224.640905523034;6008.579399442041;6299.433051025407;6310.4320611144985;6418.743250269984;5471.097268950618;5774.133879918004;6374.713137908782;6399.7440102396085;6420.165378500788;5651.463111004489;5664.461876121762;5669.121286200645;5681.715914204315;5695.0033744095035;13710.657233569142;20807.791922080887;22385.552288954128;23285;23313.76686233133;2197.4726065744267;2262.2082271205113;2271.4094335472732;2497.088562674629;2629.5661215899354;9536.189423898977;9750.756231094208;9950.800983980353;10615.46922653872;13362.465501379933;1935.0614951748391;1962.4014675523958;1972.2406873353862;1974.7235387045826;1984.593158402524;76333.61833190799;76867.6156619219;77357.61321193415;78626;79073.60463197705;10897.83653245202;10956.780864382827;11038.282511636718;11103.508458777116;11123.944380278208;15518;15552.766708499381;15650.060996340257;15657.217139142997;15662.843371566367;7731.342835859015;7964.601769911597;7972.800679983046;7978.800529986796;8048.718294859652;7395.3730179360455;7470.962645186848;7480.925190748132;7555.206703296185;7730.917671525991;4820.6143508519235;4849.709017458964;4872.098661747588;4902.142125128088;4918.040982008545;1946.922123115088;1983.7024446333166;1994.3418671838258;1999.6000799839799;2021.7068525063783;919.6367434863191;965.7199412170532;1021.065724861758;1055.1716902231865;1097.939613321259;18692;19204.74575932672;19358.903205483883;19496.1226744794;19539.218431262874;985.7880555680563;1002.9899701003043;1044.2324891204958;1056.9788604227872;1065.0946695308926;10481.42352170638;10997.12023038171;11007.394593297446;11047.83428248577;11080.224384293184;437575;442788;448098;451206;453333;54944.17583736167;71904;87802.56098719465;89476.55261723649;89553.55223223842;985.881694196701;988.2834944665204;1005.1255407795265;1013.1692012549638;1014.3964341216964;16957.32170713207;17052.744208837124;17061.914690426966;17264.482065538054;17560.82439175643;40282.395764062974;41467.37798933079;41548.37677434902;42305.365419518115;42396;53579.73210133924;54396.45603544072;60411.697941510014;61336;62286;11869.228500147463;12208;13003.934980325037;13084.28036458004;13095.672608184872;13797.79303310431;13810.930945345612;14102.083364581269;14167.929160354131;14582.270886455846;11517;11605.129615278718;11722.76554468923;11732.237404568676;11898.821517677068;11812.940935295268;12325.568605098779;12327;12606.243625382507;12798.424070916644;12034.578789742318;12116.27302361861;12242.75514489723;12292.508299667914;12345.567905123278;12982.675433113869;12996.675083122998;13029.283389413673;13042.478300868239;13049.608511744664;11578.76842463163;11608;11731.824022639837;11841.940790295994;11866.110041747077 08:59:15 INFO - PROCESS | 28303 | |3;mootools.html;1312.140547941099;1500.6923580665962;1522.8857835662325;1677.3626022111596;1835.4952388093263;8235.299999500043;8490.320774338054;8510.276626486757;8727.0836562161;8731.345149113826;8552.401331906769;8704.564771761417;8719.5640217989;8733.825323493542;8736.43213191142;2000.5098750806;2231.654093615492;2310.803581695558;2314.872682002489;2341.8594884306913;2275.7496675365696;2321.071571371448;2325.5232677301196;2327.359976006603;2328.3364241191266;2250.8762018089;2282.2696737044153;2287.977120228793;2291.472961218921;2291.8281128915314;2008.417558907915;2217.789310015554;2278.9544209115725;2287.2566415914894;2306.261996161229;2292.9541409171725;2319.652052192168;2325.837191396603;2327.8603283803027;2331.778481044315;8883.955580222122;8893.599788009491;8911.153440423213;8913.643454261823;8942.508162051085;5266.236395722637;5400.243965844785;5411.242426060355;5414.458554144593;5414.512693857532;3210.0851257391764;3307.9503807442907;3308.2556424804466;3339.5491608632765;3397.2356219850585;1021.7190272675;1026.5329275179774;1031.742064483879;1064.387976913278;1071.164491696478;12440.688982775413;12702.237865728086;12711.364431778375;12713.745725085539;12714.61856144317;4824.421069471616;4869.14789911764;4929;4938.209886418156;4947.307376967227;10770.946145269223;10782.676519704264;10796.298240614493;10800.027997480265;10809.189310801721;4696.365990591295;5452.863678408072;5510.614257001971;5511.58663100269;5525.309336332958;4890.315355850184;5023.020511000393;5029.547340739351;5065.746712664352;5066.949330506722;4128.298189307825;4163.854265100707;4171.165766846582;4220.894477638022;4227.556106608823;1302.693866941268;1320.8150858879765;1329.8071779591905;1332.4403750424844;1350.1763924006348;913.6984795017628;988.3032462114193;991.2516050381915;1005.7988402319565;1013.4122209118693;5880.411958804171;6031.638101713911;6059.151718759377;6110.358412366726;6197.96901015501;486.4770371850275;493.279811475243;498.2451585847444;502.57030239145735;502.6581924291517;3979.9204015919518;4076.7961601919787;4125.608067233608;4148.543660197376;4164.8542301019925;10924.726881827859;11200.991910728073;11201.495932683049;11224.943875280735;11275.37985410794;10595.682129535971;11050.557977680963;11144.33134011962;11222.214444988937;11237.550497980154;8329.08380078191;8413.495190288602;8430.915690843012;8504.31965442763;8518.574071296534;5903.203067585992;6084.695765211633;6223.813285601439;6247.812565623039;6272.686365681789;9854.063863932915;10348.120409764953;10463.267571269938;10472.319299245524;10476.68569942903;10223.693289201336;10418.166546676097;10442.63450779219;10689.625863095065;10691.839622405514;6738.35985581369;6924.030635710904;6924.757633482804;7796.454248202798;7969.641366138421;8019.278264956182;8509.36179786531;8801.691940782042;8807.823843523222;9012.41419307743;7211.567305961659;7398.519096258726;7521.135069466863;7851.960740196262;8101.270885620323;7315.48791584584;7339.633018349168;7339.816504587428;7472;7481.438892083224;6709.396154346132;6742.797716068526;6774.762883299061;6776.085228494288;6781.491388145811;4984.925226121683;5022.472640372708;5145.279660847559;5181.715005674724;5203.687778733288;4984.327115839315;5024.899502010013;5057.46896575869;5145.176771716508;5168.560672342893 08:59:15 INFO - PROCESS | 28303 | |4;prototype.html;316.1732070635288;320.693737480706;320.7097576693093;328.0764647517238;328.56465183631695;5300.337457817773;5313.601479889009;5332.226827110067;5349.598780091489;5385.946140538594;5230.6600070995355;5242.7640756165965;5246.658967167132;5248.921266181011;5292.153255479124;4058.2492238935743;4126.174765046988;4128.607782260696;4128.814203360856;4139.068709540352;4118.320477121271;4134.875953721378;4146.66826653867;4149.605787450188;4152.647025002879;4036.0717035081802;4068.16602596467;4081.571434999312;4083;4091.3862920561855;3881.9805900970596;3920.5883382245024;4045.939310910339;4060.776657283848;4094;3572.160542272564;3573.714102871764;3579.3020361029608;3590.874319398835;3599.6040435552068;5062.594992400599;5066.240063990394;5067.822626208102;5069.771860266297;5109.310243117169;4451.465824101097;4547.5452454754595;4597.264437689953;4629.305604159369;4697;5470.9452905470835;5496.642718223303;5510.889782204374;5516.806911758109;5524.944750552483;3933.095388060762;3945.8027098644952;3983.920321593581;3995.5005624296964;4002.0995276062945;5586.13414920688;5645.350784659776;5688.175214593902;5695.686737229451;5706.715988902505;4001.919961600752;4024.3762216856435;4026.3356546169553;4034.8298993292206;4038.5557588665224;4081.4490043844294;4095;4098.200850834119;4121.093359460914;4169.082801783603;4337.327714204303;4584.15193189261;4594.402727645384;4732;4748.264019077049;5442.755076021588;5462.453754624506;5470.604995726144;5492.313460817398;5505.449455054542;1377.937992790327;1386.3899884051186;1393.4602264497787;1393.5888912770756;1394.5258612071943;853.902734985553;877.3330671723776;893.7363477774072;893.8380105862353;900.2348004196512;4744.667873248839;4789.233722604366;4791.616670666408;4796.424429068465;4830.758462076952;4976.531923082627;4999.52504512078;5030.245463180552;5037.186612819382;5082.110630639623;4843.927341089887;4853.708777473363;4859.757012149378;5137.434882162959;5211.609129315317;2916.343822639889;3015.20097174249;3019.064090132066;3027.8031927924612;3036.30165062037;5865.445656900924;5887.882242355129;5895.056790913519;5910.172575839386;5921;5517.2551705520045;5718.25662663851;5750.613495399446;5758.452946970031;5767.250257466502;5859.384764599741;5901.232839730807;5916.171735956969;5922.963481390825;5929.288485381781;5385.27298814655;5701.828945131652;5705.830304787426;5715.199872018004;5723.3418156912485;5609.27079479665;5639.830805075854;5658.708223169623;5659.85850353728;5660.745266463102;5850.5904586678525;5905.704714764331;5935.584509084322;5944.484156540055;5957.910631340446;3725.4970578971493;3922.6861851052417;3923.5684074752326;3999.3201155804163;4011.3553443088194;5633.38032816398;5641.435856414326;5676.631018983753;5679.290088738908;5687.630304030225;5532.474414930575;5569.916451253315;5570.275864137636;5596.608237423341;5608.691521966168;5371.4897084776885;5457.262402869161;5504.80733174337;5517.523959905234;5532.612717109924;5391.7304134793885;5412;5418.187271909245;5435.456454354533;5444.782208711607;5345.685674881598;5410.918836217538;5486.725663716878;5532.612717109763;5579.944200558105;5374.495141360425;5393.244945707525;5419.485148910847;5437.374701909331;5456.9618482323585 08:59:15 INFO - PROCESS | 28303 | |5;yui.html;696.3264821665249;754.4304050441915;762.816923938255;775.4959276470297;793.4445887878483;11223.270487418313;12662.303573303465;13267.402966866503;13673.521426750065;13837.100588461743;13372.732545349087;13818.170909745397;13887.652808679788;13922.582322530341;13933.094348867317;679.7009315900988;822.3626689315784;823.5964377455064;829.2412442615;829.3655353654459;669.7990602819159;692.2973182220051;798.0861913109486;811.930985866202;812.6383759227147;865.5066612031145;866.4714524140269;866.9349798765086;870.1994165367845;870.8911386076741;861.9741407757778;862.9137086291383;864.0581765875163;864.4553931023446;864.8183881384916;628.4249911331126;630.014028046111;630.0549176235646;630.1492984470973;630.5145038320468;12912.677183070498;12925.741485170338;12931.224126552437;12963.157394769403;12971.35143242834;12953.740925181537;12991.870081299161;13009.544665936646;13056.932224956281;13068.607941761762;10674;10722.678319650424;10910.890891091067;11075.50160242791;11189.384583847885;3762.5673047599603;3764.698824094066;3841;3860.7104467165077;3934.390169523728;54037;55648.16527752127;55703;55742.44257557413;56218.43781562173;10497.527611257512;10570.471476426148;10590.86286318222;10597.41714205726;10876.238663293652;17958.102094895203;19019.42941711751;19027.14377853;19044.61910761777;19119.426417207505;8326.29226515753;8327.958360208282;8328.8334233315;8392.496450213006;8571.57142142903;4214.7471151731;4607.746573938465;4657.324687920301;4769.260764581426;4994.875128121826;3249.918752031218;3436.7078798301923;3438.1404648837793;3477.4609935460044;3477.791332520057;650.9674516274167;660.86452277284;664.2659860853687;673.9865202696017;675.4663815585731;657.983550411234;676.3658722838742;752.9322360987537;756.9772906812805;769.9730509432143;8968.955155224312;9054.194698550504;9143.954280228556;9279.67521136757;9418.058194180527;593.6942474625571;644.9677516124175;672.640137526421;673.3636713305879;679.4496457869142;4260.318349064197;4397.428334316518;4441.111777644484;4443.955560444419;4472.351509031172;73955.63022184963;74939;75473;76611;80056.99572531966;48642.513574864504;54566.45433545693;61736;67259;68980.3101968984;59221.40778592245;62656;63406;63486;63930.6803465989;8566.100559441169;8611.397202195787;8668.523231222343;8694.782630434289;8785.868211976953;39950.80024599859;40377;40460.3930941042;40514.59485405108;40875.1824963505;52245.477545225585;54684.17973730477;55018.17472737832;55907.44092559022;56022.43977560172;11200.887991120311;11238;11246.156538259827;11375.31748095117;11842.822357664814;11400;11624.767504650028;11986.520539178335;12026.879731202926;12541.372931353213;11173.580105260597;11529.711757206136;11713.824292635447;11941.481195336763;12086.456109474919;11117.888821111685;11302.660920172408;11436.599719009795;11457.197996140523;11570.59502917394;11148.721281968015;11403.144764142557;11411;11505.654830355103;11544.845515448387;8673.956630216808;8706.1729135732;8746.562671866508;9009.54952252398;9088.863667045132;8786.121387861163;9430.669926552539;9661.661841835503;9664.56509457062;9669.758256043655 08:59:15 INFO - PROCESS | 28303 | __end_tp_report 08:59:15 INFO - PROCESS | 28303 | __start_cc_report 08:59:15 INFO - PROCESS | 28303 | _x_x_mozilla_cycle_collect,881 08:59:15 INFO - PROCESS | 28303 | __end_cc_report 08:59:15 INFO - PROCESS | 28303 | __startTimestamp1464623955618__endTimestamp 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | ------- Summary: start ------- 08:59:15 INFO - PROCESS | 28303 | Number of tests: 6 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | [#0] dojo.html Cycles:165 Average:5449.09 Median:5648.52 stddev:622.62 (11.0%) stddev-sans-first:596.63 08:59:15 INFO - PROCESS | 28303 | Values: 3099.6 3137.9 3144.0 3174.9 3181.8 5250.3 5366.0 5382.8 5490.8 5493.5 5478.2 5511.0 5523.8 5560.3 5564.9 4932.7 4936.9 5114.1 5170.1 5195.4 5082.1 5082.9 5138.1 5156.7 5157.0 4811.3 4945.7 4989.1 4998.5 5007.6 5017.6 5019.4 5020.0 5026.2 5069.9 4981.4 5012.1 5022.5 5039.3 5086.9 5230.6 5310.3 5315.9 5355.4 5358.5 5751.6 5793.4 5825.8 5869.5 5880.9 5754.8 5876.6 5917.8 5921.8 5928.9 5380.3 5433.9 5443.5 5525.6 5529.3 6023.5 6046.2 6049.6 6053.5 6060.7 5603.7 5662.3 5805.2 5811.6 5817.5 5761.5 5824.2 5867.0 5868.2 5869.2 5810.9 5816.9 5820.7 5823.5 5823.6 5482.9 5746.1 5823.0 5830.4 5835.0 4457.4 4459.2 4467.0 4498.5 4499.2 3961.4 3979.3 3981.2 4002.5 4015.1 5333.5 5355.0 5485.3 5534.9 5599.3 5856.1 5864.5 5882.6 5885.4 5928.6 5562.5 5582.7 5582.8 5585.1 5588.2 4907.6 5059.2 5101.6 5113.4 5118.8 6189.1 6192.3 6193.1 6196.4 6197.3 5710.6 5959.9 5968.9 5973.0 5977.4 6020.5 6033.3 6068.2 6100.7 6107.7 5716.9 5730.4 5760.8 5782.7 5829.3 5801.7 5871.9 5873.5 5875.2 5875.6 5513.3 5796.1 5817.4 5839.4 5889.7 5789.7 5790.4 5794.8 5794.9 5806.7 5446.1 5706.8 5713.9 5721.5 5722.2 5682.8 5736.7 5741.3 5805.5 5820.8 5609.6 5644.5 5647.0 5648.5 5652.2 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | [#1] ext.html Cycles:180 Average:49549.54 Median:421107.50 stddev:86074.34 (20.4%) stddev-sans-first:86237.42 08:59:15 INFO - PROCESS | 28303 | Values: 625.2 628.9 637.4 638.3 647.1 42311.4 43840.6 43871.8 48342.3 48387.0 45485.0 46152.0 46196.0 46257.5 46288.8 8693.4 8867.0 8867.3 8889.6 8894.3 8761.1 8850.5 8852.6 8857.3 8861.0 8821.6 8837.7 8876.2 8878.2 8884.1 8841.0 8892.0 8894.2 8896.9 8897.9 8514.5 8563.9 8573.1 8574.7 8616.1 42307.4 42600.8 42736.4 42741.6 42750.8 3525.2 3537.6 3662.9 3683.3 3940.5 32490.4 32815.3 33369.8 33760.0 34082.3 4329.9 4382.7 4405.6 4416.6 4436.3 343266.0 343294.0 343313.3 343881.0 345693.0 57125.4 57295.1 57510.4 57672.7 57710.1 52255.0 57282.1 58781.4 59384.4 64687.4 14729.7 14810.1 14875.7 14878.2 14879.9 11366.7 11456.8 11460.9 11472.7 11477.2 14218.3 15844.3 15904.6 16232.6 16654.0 6135.4 6194.9 6264.5 6384.4 6390.7 872.4 1009.2 1023.1 1033.7 1036.4 32895.5 32925.2 32997.5 33248.7 33411.8 1112.4 1117.1 1123.1 1125.6 1167.9 16530.5 17089.7 17175.6 17241.7 17378.7 151079.0 151695.0 152636.0 153066.0 153279.0 59256.7 59350.4 59429.7 59489.1 59497.7 1262.3 1371.1 1385.8 1392.1 1403.8 32003.0 33373.5 33776.1 33802.0 34247.1 49638.8 50015.5 50037.2 50091.7 50093.2 418249.0 420229.0 420712.0 421503.0 421933.0 31151.1 35516.5 35639.3 38484.6 38603.8 47385.5 47996.8 48496.8 49400.3 49431.5 39804.6 40257.8 41271.0 41372.2 42172.2 40335.2 40839.8 40923.4 40926.2 41657.2 39457.6 40791.8 41077.8 41186.2 41683.4 42295.6 43493.8 43526.6 46648.5 47182.3 32152.7 33252.0 35283.1 35457.3 35926.1 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | [#2] jquery.html Cycles:180 Average:27552.39 Median:1996.97 stddev:73527.42 (3681.9%) stddev-sans-first:73705.41 08:59:15 INFO - PROCESS | 28303 | Values: 395.7 403.6 406.8 412.8 426.4 13034.5 17942.7 19194.1 19329.2 19374.3 18319.4 18614.5 18778.0 19107.4 19123.2 5676.3 5739.3 5789.6 5808.5 5849.6 5679.2 5722.6 5731.2 5735.4 5766.9 4224.6 6008.6 6299.4 6310.4 6418.7 5471.1 5774.1 6374.7 6399.7 6420.2 5651.5 5664.5 5669.1 5681.7 5695.0 13710.7 20807.8 22385.6 23285.0 23313.8 2197.5 2262.2 2271.4 2497.1 2629.6 9536.2 9750.8 9950.8 10615.5 13362.5 1935.1 1962.4 1972.2 1974.7 1984.6 76333.6 76867.6 77357.6 78626.0 79073.6 10897.8 10956.8 11038.3 11103.5 11123.9 15518.0 15552.8 15650.1 15657.2 15662.8 7731.3 7964.6 7972.8 7978.8 8048.7 7395.4 7471.0 7480.9 7555.2 7730.9 4820.6 4849.7 4872.1 4902.1 4918.0 1946.9 1983.7 1994.3 1999.6 2021.7 919.6 965.7 1021.1 1055.2 1097.9 18692.0 19204.7 19358.9 19496.1 19539.2 985.8 1003.0 1044.2 1057.0 1065.1 10481.4 10997.1 11007.4 11047.8 11080.2 437575.0 442788.0 448098.0 451206.0 453333.0 54944.2 71904.0 87802.6 89476.6 89553.6 985.9 988.3 1005.1 1013.2 1014.4 16957.3 17052.7 17061.9 17264.5 17560.8 40282.4 41467.4 41548.4 42305.4 42396.0 53579.7 54396.5 60411.7 61336.0 62286.0 11869.2 12208.0 13003.9 13084.3 13095.7 13797.8 13810.9 14102.1 14167.9 14582.3 11517.0 11605.1 11722.8 11732.2 11898.8 11812.9 12325.6 12327.0 12606.2 12798.4 12034.6 12116.3 12242.8 12292.5 12345.6 12982.7 12996.7 13029.3 13042.5 13049.6 11578.8 11608.0 11731.8 11841.9 11866.1 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | [#3] mootools.html Cycles:180 Average:5837.03 Median:4909.66 stddev:3383.05 (68.9%) stddev-sans-first:3375.44 08:59:15 INFO - PROCESS | 28303 | Values: 1312.1 1500.7 1522.9 1677.4 1835.5 8235.3 8490.3 8510.3 8727.1 8731.3 8552.4 8704.6 8719.6 8733.8 8736.4 2000.5 2231.7 2310.8 2314.9 2341.9 2275.7 2321.1 2325.5 2327.4 2328.3 2250.9 2282.3 2288.0 2291.5 2291.8 2008.4 2217.8 2279.0 2287.3 2306.3 2293.0 2319.7 2325.8 2327.9 2331.8 8884.0 8893.6 8911.2 8913.6 8942.5 5266.2 5400.2 5411.2 5414.5 5414.5 3210.1 3308.0 3308.3 3339.5 3397.2 1021.7 1026.5 1031.7 1064.4 1071.2 12440.7 12702.2 12711.4 12713.7 12714.6 4824.4 4869.1 4929.0 4938.2 4947.3 10770.9 10782.7 10796.3 10800.0 10809.2 4696.4 5452.9 5510.6 5511.6 5525.3 4890.3 5023.0 5029.5 5065.7 5066.9 4128.3 4163.9 4171.2 4220.9 4227.6 1302.7 1320.8 1329.8 1332.4 1350.2 913.7 988.3 991.3 1005.8 1013.4 5880.4 6031.6 6059.2 6110.4 6198.0 486.5 493.3 498.2 502.6 502.7 3979.9 4076.8 4125.6 4148.5 4164.9 10924.7 11201.0 11201.5 11224.9 11275.4 10595.7 11050.6 11144.3 11222.2 11237.6 8329.1 8413.5 8430.9 8504.3 8518.6 5903.2 6084.7 6223.8 6247.8 6272.7 9854.1 10348.1 10463.3 10472.3 10476.7 10223.7 10418.2 10442.6 10689.6 10691.8 6738.4 6924.0 6924.8 7796.5 7969.6 8019.3 8509.4 8801.7 8807.8 9012.4 7211.6 7398.5 7521.1 7852.0 8101.3 7315.5 7339.6 7339.8 7472.0 7481.4 6709.4 6742.8 6774.8 6776.1 6781.5 4984.9 5022.5 5145.3 5181.7 5203.7 4984.3 5024.9 5057.5 5145.2 5168.6 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | [#4] prototype.html Cycles:180 Average:4599.01 Median:5236.71 stddev:1361.73 (26.0%) stddev-sans-first:1327.07 08:59:15 INFO - PROCESS | 28303 | Values: 316.2 320.7 320.7 328.1 328.6 5300.3 5313.6 5332.2 5349.6 5385.9 5230.7 5242.8 5246.7 5248.9 5292.2 4058.2 4126.2 4128.6 4128.8 4139.1 4118.3 4134.9 4146.7 4149.6 4152.6 4036.1 4068.2 4081.6 4083.0 4091.4 3882.0 3920.6 4045.9 4060.8 4094.0 3572.2 3573.7 3579.3 3590.9 3599.6 5062.6 5066.2 5067.8 5069.8 5109.3 4451.5 4547.5 4597.3 4629.3 4697.0 5470.9 5496.6 5510.9 5516.8 5524.9 3933.1 3945.8 3983.9 3995.5 4002.1 5586.1 5645.4 5688.2 5695.7 5706.7 4001.9 4024.4 4026.3 4034.8 4038.6 4081.4 4095.0 4098.2 4121.1 4169.1 4337.3 4584.2 4594.4 4732.0 4748.3 5442.8 5462.5 5470.6 5492.3 5505.4 1377.9 1386.4 1393.5 1393.6 1394.5 853.9 877.3 893.7 893.8 900.2 4744.7 4789.2 4791.6 4796.4 4830.8 4976.5 4999.5 5030.2 5037.2 5082.1 4843.9 4853.7 4859.8 5137.4 5211.6 2916.3 3015.2 3019.1 3027.8 3036.3 5865.4 5887.9 5895.1 5910.2 5921.0 5517.3 5718.3 5750.6 5758.5 5767.3 5859.4 5901.2 5916.2 5923.0 5929.3 5385.3 5701.8 5705.8 5715.2 5723.3 5609.3 5639.8 5658.7 5659.9 5660.7 5850.6 5905.7 5935.6 5944.5 5957.9 3725.5 3922.7 3923.6 3999.3 4011.4 5633.4 5641.4 5676.6 5679.3 5687.6 5532.5 5569.9 5570.3 5596.6 5608.7 5371.5 5457.3 5504.8 5517.5 5532.6 5391.7 5412.0 5418.2 5435.5 5444.8 5345.7 5410.9 5486.7 5532.6 5579.9 5374.5 5393.2 5419.5 5437.4 5457.0 08:59:15 INFO - PROCESS | 28303 | 08:59:15 INFO - PROCESS | 28303 | [#5] yui.html Cycles:180 Average:15778.05 Median:55333.17 stddev:20184.96 (36.5%) stddev-sans-first:20209.81 08:59:15 INFO - PROCESS | 28303 | Values: 696.3 754.4 762.8 775.5 793.4 11223.3 12662.3 13267.4 13673.5 13837.1 13372.7 13818.2 13887.7 13922.6 13933.1 679.7 822.4 823.6 829.2 829.4 669.8 692.3 798.1 811.9 812.6 865.5 866.5 866.9 870.2 870.9 862.0 862.9 864.1 864.5 864.8 628.4 630.0 630.1 630.1 630.5 12912.7 12925.7 12931.2 12963.2 12971.4 12953.7 12991.9 13009.5 13056.9 13068.6 10674.0 10722.7 10910.9 11075.5 11189.4 3762.6 3764.7 3841.0 3860.7 3934.4 54037.0 55648.2 55703.0 55742.4 56218.4 10497.5 10570.5 10590.9 10597.4 10876.2 17958.1 19019.4 19027.1 19044.6 19119.4 8326.3 8328.0 8328.8 8392.5 8571.6 4214.7 4607.7 4657.3 4769.3 4994.9 3249.9 3436.7 3438.1 3477.5 3477.8 651.0 660.9 664.3 674.0 675.5 658.0 676.4 752.9 757.0 770.0 8969.0 9054.2 9144.0 9279.7 9418.1 593.7 645.0 672.6 673.4 679.4 4260.3 4397.4 4441.1 4444.0 4472.4 73955.6 74939.0 75473.0 76611.0 80057.0 48642.5 54566.5 61736.0 67259.0 68980.3 59221.4 62656.0 63406.0 63486.0 63930.7 8566.1 8611.4 8668.5 8694.8 8785.9 39950.8 40377.0 40460.4 40514.6 40875.2 52245.5 54684.2 55018.2 55907.4 56022.4 11200.9 11238.0 11246.2 11375.3 11842.8 11400.0 11624.8 11986.5 12026.9 12541.4 11173.6 11529.7 11713.8 11941.5 12086.5 11117.9 11302.7 11436.6 11457.2 11570.6 11148.7 11403.1 11411.0 11505.7 11544.8 8674.0 8706.2 8746.6 9009.5 9088.9 8786.1 9430.7 9661.7 9664.6 9669.8 08:59:15 INFO - PROCESS | 28303 | -------- Summary: end -------- 08:59:15 INFO - PROCESS | 28303 | 08:59:16 INFO - TEST-INFO | 28303: exit 0 08:59:16 INFO - TEST-OK | dromaeo_css | took 1113596ms 08:59:16 INFO - TEST-START | kraken 08:59:16 INFO - operating with platform_type : linux_ 08:59:16 INFO - Initialising browser for kraken test... 08:59:16 INFO - TEST-INFO | started process 28482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Zm_RT/profile http://localhost:60689/getInfo.html) 08:59:20 INFO - PROCESS | 28482 | __metrics Screen width/height:1600/1200 08:59:20 INFO - PROCESS | 28482 | colorDepth:24 08:59:20 INFO - PROCESS | 28482 | Browser inner width/height: 1024/697 08:59:20 INFO - PROCESS | 28482 | __metrics 08:59:21 ERROR - PROCESS | 28482 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:59:22 INFO - TEST-INFO | 28482: exit 0 08:59:22 INFO - Browser initialized. 08:59:22 INFO - Running cycle 1/1 for kraken test... 08:59:22 INFO - TEST-INFO | started process 28565 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Zm_RT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:59:34 INFO - PROCESS | 28565 | RSS: Main: 156016640 08:59:34 INFO - PROCESS | 28565 | 08:59:36 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60689/tests/kraken/driver.html?testName=audio-beat-detection) 08:59:37 INFO - PROCESS | 28565 | RSS: Main: 214401024 08:59:37 INFO - PROCESS | 28565 | 08:59:40 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60689/tests/kraken/driver.html?testName=audio-dft) 08:59:40 INFO - PROCESS | 28565 | RSS: Main: 199102464 08:59:40 INFO - PROCESS | 28565 | 08:59:43 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60689/tests/kraken/driver.html?testName=audio-fft) 08:59:44 INFO - PROCESS | 28565 | RSS: Main: 268607488 08:59:44 INFO - PROCESS | 28565 | 08:59:46 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60689/tests/kraken/driver.html?testName=audio-oscillator) 08:59:47 INFO - PROCESS | 28565 | RSS: Main: 178798592 08:59:47 INFO - PROCESS | 28565 | 08:59:49 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60689/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:59:49 INFO - PROCESS | 28565 | RSS: Main: 183341056 08:59:49 INFO - PROCESS | 28565 | 08:59:52 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60689/tests/kraken/driver.html?testName=imaging-darkroom) 08:59:53 INFO - PROCESS | 28565 | RSS: Main: 217870336 08:59:53 INFO - PROCESS | 28565 | 08:59:56 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60689/tests/kraken/driver.html?testName=imaging-desaturate) 08:59:57 INFO - PROCESS | 28565 | RSS: Main: 220094464 08:59:57 INFO - PROCESS | 28565 | 09:00:00 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60689/tests/kraken/driver.html?testName=json-parse-financial) 09:00:00 INFO - PROCESS | 28565 | RSS: Main: 219942912 09:00:00 INFO - PROCESS | 28565 | 09:00:02 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60689/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:00:03 INFO - PROCESS | 28565 | RSS: Main: 208629760 09:00:03 INFO - PROCESS | 28565 | 09:00:04 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:00:05 INFO - PROCESS | 28565 | RSS: Main: 209547264 09:00:05 INFO - PROCESS | 28565 | 09:00:07 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:00:08 INFO - PROCESS | 28565 | RSS: Main: 215859200 09:00:08 INFO - PROCESS | 28565 | 09:00:10 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:00:11 INFO - PROCESS | 28565 | RSS: Main: 223850496 09:00:11 INFO - PROCESS | 28565 | 09:00:14 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:00:14 INFO - PROCESS | 28565 | RSS: Main: 223805440 09:00:14 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | Cycle 1(1): loaded http://localhost:60689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60689/tests/kraken/driver.html?testName=ai-astar) 09:00:16 INFO - PROCESS | 28565 | RSS: Main: 266567680 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | __start_tp_report 09:00:16 INFO - PROCESS | 28565 | _x_x_mozilla_page_load 09:00:16 INFO - PROCESS | 28565 | _x_x_mozilla_page_load_details 09:00:16 INFO - PROCESS | 28565 | |i|pagename|runs| 09:00:16 INFO - PROCESS | 28565 | |0;ai-astar;110;105;110;110;110;110;110;111;109;109 09:00:16 INFO - PROCESS | 28565 | |1;audio-beat-detection;150;124;127;180;130;126;151;127;154;130 09:00:16 INFO - PROCESS | 28565 | |2;audio-dft;160;161;160;161;161;161;162;160;163;161 09:00:16 INFO - PROCESS | 28565 | |3;audio-fft;74;74;74;126;74;75;73;74;102;73 09:00:16 INFO - PROCESS | 28565 | |4;audio-oscillator;83;83;114;83;84;84;110;85;84;128 09:00:16 INFO - PROCESS | 28565 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;117;117;117 09:00:16 INFO - PROCESS | 28565 | |6;imaging-darkroom;145;147;146;145;146;146;146;146;145;145 09:00:16 INFO - PROCESS | 28565 | |7;imaging-desaturate;94;95;95;95;94;95;95;95;95;95 09:00:16 INFO - PROCESS | 28565 | |8;json-parse-financial;55;53;53;53;52;52;52;53;53;53 09:00:16 INFO - PROCESS | 28565 | |9;json-stringify-tinderbox;62;62;61;62;62;62;66;63;63;62 09:00:16 INFO - PROCESS | 28565 | |10;stanford-crypto-aes;81;84;78;78;83;83;90;81;120;86 09:00:16 INFO - PROCESS | 28565 | |11;stanford-crypto-ccm;122;122;119;118;128;117;126;120;134;133 09:00:16 INFO - PROCESS | 28565 | |12;stanford-crypto-pbkdf2;160;163;160;156;156;157;161;164;162;158 09:00:16 INFO - PROCESS | 28565 | |13;stanford-crypto-sha256-iterative;62;63;67;61;62;63;62;62;60;65 09:00:16 INFO - PROCESS | 28565 | __end_tp_report 09:00:16 INFO - PROCESS | 28565 | __start_cc_report 09:00:16 INFO - PROCESS | 28565 | _x_x_mozilla_cycle_collect,1669 09:00:16 INFO - PROCESS | 28565 | __end_cc_report 09:00:16 INFO - PROCESS | 28565 | __startTimestamp1464624016578__endTimestamp 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | ------- Summary: start ------- 09:00:16 INFO - PROCESS | 28565 | Number of tests: 14 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:1.65 (1.5%) stddev-sans-first:1.73 09:00:16 INFO - PROCESS | 28565 | Values: 110.0 105.0 110.0 110.0 110.0 110.0 110.0 111.0 109.0 109.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:140.00 stddev:18.28 (13.1%) stddev-sans-first:19.02 09:00:16 INFO - PROCESS | 28565 | Values: 150.0 124.0 127.0 180.0 130.0 126.0 151.0 127.0 154.0 130.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 09:00:16 INFO - PROCESS | 28565 | Values: 160.0 161.0 160.0 161.0 161.0 161.0 162.0 160.0 163.0 161.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#3] audio-fft Cycles:10 Average:81.90 Median:74.00 stddev:17.85 (24.1%) stddev-sans-first:18.70 09:00:16 INFO - PROCESS | 28565 | Values: 74.0 74.0 74.0 126.0 74.0 75.0 73.0 74.0 102.0 73.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:83.50 stddev:16.85 (20.2%) stddev-sans-first:17.41 09:00:16 INFO - PROCESS | 28565 | Values: 83.0 83.0 114.0 83.0 84.0 84.0 110.0 85.0 84.0 128.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:00:16 INFO - PROCESS | 28565 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:00:16 INFO - PROCESS | 28565 | Values: 145.0 147.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:00:16 INFO - PROCESS | 28565 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.88 (1.7%) stddev-sans-first:0.50 09:00:16 INFO - PROCESS | 28565 | Values: 55.0 53.0 53.0 53.0 52.0 52.0 52.0 53.0 53.0 53.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.42 09:00:16 INFO - PROCESS | 28565 | Values: 62.0 62.0 61.0 62.0 62.0 62.0 66.0 63.0 63.0 62.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:83.00 stddev:12.34 (14.9%) stddev-sans-first:12.93 09:00:16 INFO - PROCESS | 28565 | Values: 81.0 84.0 78.0 78.0 83.0 83.0 90.0 81.0 120.0 86.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:124.00 stddev:6.10 (4.9%) stddev-sans-first:6.43 09:00:16 INFO - PROCESS | 28565 | Values: 122.0 122.0 119.0 118.0 128.0 117.0 126.0 120.0 134.0 133.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.50 stddev:2.87 (1.8%) stddev-sans-first:3.04 09:00:16 INFO - PROCESS | 28565 | Values: 160.0 163.0 160.0 156.0 156.0 157.0 161.0 164.0 162.0 158.0 09:00:16 INFO - PROCESS | 28565 | 09:00:16 INFO - PROCESS | 28565 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:2.00 (3.2%) stddev-sans-first:2.11 09:00:16 INFO - PROCESS | 28565 | Values: 62.0 63.0 67.0 61.0 62.0 63.0 62.0 62.0 60.0 65.0 09:00:16 INFO - PROCESS | 28565 | -------- Summary: end -------- 09:00:16 INFO - PROCESS | 28565 | 09:00:17 INFO - TEST-INFO | 28565: exit 0 09:00:17 INFO - TEST-OK | kraken | took 61149ms 09:00:17 INFO - SUITE-END | took 1174s 09:00:17 INFO - Completed test suite (00:19:34) 09:00:17 INFO - javascript benchmark 09:00:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3099.628044634644, 3137.8744850205994, 3143.968560314397, 3174.904752857415, 3181.793183443076, 5250.31745873036, 5366.0, 5382.757775900084, 5490.780368785248, 5493.45065493451, 5478.233047373371, 5510.972445137769, 5523.7790488380415, 5560.277163968689, 5564.86087847804, 4932.654714170009, 4936.901261974767, 5114.105031619451, 5170.121079416508, 5195.376554813428, 5082.110630639641, 5082.898342033157, 5138.100832354342, 5156.69059856408, 5156.974215128919, 4811.254255590372, 4945.653804233705, 4989.126902792033, 4998.525140111684, 5007.649464537484, 5017.623678224148, 5019.3976722793495, 5020.0, 5026.195808670595, 5069.949300506984, 4981.352424184833, 5012.122878496249, 5022.547970682656, 5039.3196918415915, 5086.949130508685, 5230.581553475713, 5310.336207974004, 5315.867103322409, 5355.384130824966, 5358.517733403974, 5751.626144300607, 5793.391693872167, 5825.766969321222, 5869.4717475427415, 5880.852978675524, 5754.79858204965, 5876.6180198286975, 5917.792877249319, 5921.792737254175, 5928.911066333965, 5380.3274590676165, 5433.864153396157, 5443.537299329561, 5525.613207075467, 5529.30883639545, 6023.457888789942, 6046.244219472566, 6049.576529642883, 6053.4551890330085, 6060.727267272983, 5603.663780173202, 5662.320521537359, 5805.187273781674, 5811.622244554091, 5817.505512031525, 5761.452661997104, 5824.213731146326, 5867.0, 5868.178455016301, 5869.20765696634, 5810.854728631818, 5816.883662326814, 5820.679862607597, 5823.505002074787, 5823.56323275756, 5482.91775623366, 5746.080627099644, 5823.0, 5830.387809279964, 5834.970825145932, 4457.398251236042, 4459.175052615276, 4467.0, 4498.50516443191, 4499.212637788374, 3961.3661814109605, 3979.303621866162, 3981.203759248162, 4002.4996875390575, 4015.0966032642423, 5333.493318134771, 5355.036093503207, 5485.259489968803, 5534.889302213933, 5599.300087489064, 5856.092305692625, 5864.472197502245, 5882.588218824641, 5885.411458854081, 5928.614640048383, 5562.527185189304, 5582.692951887604, 5582.804601838916, 5585.078462053716, 5588.189712491747, 4907.607391408679, 5059.241113832881, 5101.617378696538, 5113.411957624847, 5118.846434606968, 6189.071639254058, 6192.287886892975, 6193.102000210034, 6196.442320191295, 6197.318294987638, 5710.600257981901, 5959.851003724942, 5968.910466342922, 5973.0, 5977.432143946318, 6020.548458865515, 6033.276006879202, 6068.180795592715, 6100.73256437228, 6107.6671455628975, 5716.942830571641, 5730.398308177581, 5760.798372056958, 5782.739776710142, 5829.329627092939, 5801.680907549957, 5871.882562348815, 5873.471387575139, 5875.236219291635, 5875.618084824644, 5513.255710479034, 5796.072628379439, 5817.447342502455, 5839.35767065614, 5889.676067816311, 5789.710514474175, 5790.392008839011, 5794.82615521535, 5794.855128621818, 5806.68063256525, 5446.074167391474, 5706.828795136153, 5713.885722285448, 5721.485066343883, 5722.198892155018, 5682.829515114553, 5736.741846616996, 5741.339745929272, 5805.535557155333, 5820.796272130455, 5609.635373700697, 5644.520215781705, 5646.971765141148, 5648.519875810603, 5652.236948011966], "value": 5406.786136334539, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.1966223402928, 628.9402506761858, 637.356270167131, 638.307436431472, 647.1425361396151, 42311.36532952004, 43840.561594384046, 43871.78064109679, 48342.27486587704, 48387.0, 45485.0, 46152.0, 46196.0, 46257.53742462574, 46288.76855615726, 8693.391462597636, 8867.024627290994, 8867.334949878752, 8889.555522223895, 8894.28845692343, 8761.123887611251, 8850.468971861677, 8852.5573721314, 8857.291416686681, 8860.955695221515, 8821.558922053904, 8837.73486795394, 8876.245519130882, 8878.156575125386, 8884.11158884113, 8841.0, 8892.0, 8894.199522043014, 8896.911030889738, 8897.911020889773, 8514.48913065218, 8563.871541926876, 8573.056963733985, 8574.742757717278, 8616.095309992486, 42307.36538951918, 42600.78699606513, 42736.35895461571, 42741.57258427407, 42750.786246068885, 3525.2420729543014, 3537.6108628050893, 3662.9084272893124, 3683.318586061587, 3940.54683711374, 32490.350192996008, 32815.34369312624, 33369.83315083433, 33760.0, 34082.31835363304, 4329.9134017319475, 4382.715123516993, 4405.559444055601, 4416.6025057744955, 4436.26587834989, 343266.0, 343294.0, 343313.28343358374, 343881.0, 345693.0, 57125.42874571201, 57295.14057289144, 57510.42489575134, 57672.71163644155, 57710.134347984815, 52255.0, 57282.140767888515, 58781.41218587845, 59384.40615593875, 64687.353126469076, 14729.705405891822, 14810.111393316436, 14875.70248595022, 14878.181700006387, 14879.851201487847, 11366.659000230007, 11456.828147577793, 11460.94269528647, 11472.655820325404, 11477.196596238184, 14218.28908554568, 15844.28700708471, 15904.602384940237, 16232.594185145466, 16654.0, 6135.417135372132, 6194.946859033975, 6264.467520260829, 6384.361563843671, 6390.7443702251385, 872.437277955715, 1009.1623952119724, 1023.0919335439501, 1033.7105610429091, 1036.4403222260046, 32895.506567401506, 32925.17687057843, 32997.50503742446, 33248.66751332504, 33411.83294083514, 1112.3993043756282, 1117.1230583991528, 1123.1071298317856, 1125.58915995661, 1167.871534131245, 16530.504084877473, 17089.743653845202, 17175.57061073483, 17241.65516689659, 17378.739318910226, 151079.0, 151695.0, 152636.0, 153066.0, 153279.0, 59256.70371648114, 59350.40649593535, 59429.70285148547, 59489.10766338508, 59497.702511487165, 1262.299423819789, 1371.1019282370064, 1385.8267716535433, 1392.0812263905773, 1403.8034675145489, 32003.0, 33373.499397508574, 33776.10246514291, 33802.0, 34247.143821404665, 49638.75180624074, 50015.49984500254, 50037.24944125914, 50091.74954125206, 50093.24860127174, 418249.0, 420229.0, 420712.0, 421503.0, 421933.0, 31151.071129586366, 35516.46725299071, 35639.28721425505, 38484.615153848106, 38603.80698096604, 47385.52614473949, 47996.760016199696, 48496.757516212194, 49400.25899611581, 49431.50568494269, 39804.60195398125, 40257.79871100743, 41271.0, 41372.172556548096, 42172.156556868074, 40335.19329613333, 40839.795801021995, 40923.38614920838, 40926.181476370904, 41657.1668566633, 39457.605423945395, 40791.7960410196, 41077.79461102675, 41186.17627647371, 41683.37474937939, 42295.57704422916, 43493.78253108841, 43526.56473435352, 46648.533514665774, 47182.29226561535, 32152.678473215903, 33252.0, 35283.11792205215, 35457.290854182254, 35926.101847454025], "value": 19571.623216960514, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.673569305323, 403.58632401788145, 406.83319838866066, 412.7771003658023, 426.38174646762184, 13034.543790967318, 17942.73085903711, 19194.136263868124, 19329.226830926764, 19374.321898733546, 18319.358822441216, 18614.53463663409, 18778.0, 19107.426777196673, 19123.23507059716, 5676.290463692048, 5739.339975902762, 5789.565782566325, 5808.506276966463, 5849.561282903778, 5679.176519404684, 5722.599418040739, 5731.168980497825, 5735.369109397963, 5766.913496297538, 4224.640905523034, 6008.579399442041, 6299.433051025407, 6310.4320611144985, 6418.743250269984, 5471.097268950618, 5774.133879918004, 6374.713137908782, 6399.7440102396085, 6420.165378500788, 5651.463111004489, 5664.461876121762, 5669.121286200645, 5681.715914204315, 5695.0033744095035, 13710.657233569142, 20807.791922080887, 22385.552288954128, 23285.0, 23313.76686233133, 2197.4726065744267, 2262.2082271205113, 2271.4094335472732, 2497.088562674629, 2629.5661215899354, 9536.189423898977, 9750.756231094208, 9950.800983980353, 10615.46922653872, 13362.465501379933, 1935.0614951748391, 1962.4014675523958, 1972.2406873353862, 1974.7235387045826, 1984.593158402524, 76333.61833190799, 76867.6156619219, 77357.61321193415, 78626.0, 79073.60463197705, 10897.83653245202, 10956.780864382827, 11038.282511636718, 11103.508458777116, 11123.944380278208, 15518.0, 15552.766708499381, 15650.060996340257, 15657.217139142997, 15662.843371566367, 7731.342835859015, 7964.601769911597, 7972.800679983046, 7978.800529986796, 8048.718294859652, 7395.3730179360455, 7470.962645186848, 7480.925190748132, 7555.206703296185, 7730.917671525991, 4820.6143508519235, 4849.709017458964, 4872.098661747588, 4902.142125128088, 4918.040982008545, 1946.922123115088, 1983.7024446333166, 1994.3418671838258, 1999.6000799839799, 2021.7068525063783, 919.6367434863191, 965.7199412170532, 1021.065724861758, 1055.1716902231865, 1097.939613321259, 18692.0, 19204.74575932672, 19358.903205483883, 19496.1226744794, 19539.218431262874, 985.7880555680563, 1002.9899701003043, 1044.2324891204958, 1056.9788604227872, 1065.0946695308926, 10481.42352170638, 10997.12023038171, 11007.394593297446, 11047.83428248577, 11080.224384293184, 437575.0, 442788.0, 448098.0, 451206.0, 453333.0, 54944.17583736167, 71904.0, 87802.56098719465, 89476.55261723649, 89553.55223223842, 985.881694196701, 988.2834944665204, 1005.1255407795265, 1013.1692012549638, 1014.3964341216964, 16957.32170713207, 17052.744208837124, 17061.914690426966, 17264.482065538054, 17560.82439175643, 40282.395764062974, 41467.37798933079, 41548.37677434902, 42305.365419518115, 42396.0, 53579.73210133924, 54396.45603544072, 60411.697941510014, 61336.0, 62286.0, 11869.228500147463, 12208.0, 13003.934980325037, 13084.28036458004, 13095.672608184872, 13797.79303310431, 13810.930945345612, 14102.083364581269, 14167.929160354131, 14582.270886455846, 11517.0, 11605.129615278718, 11722.76554468923, 11732.237404568676, 11898.821517677068, 11812.940935295268, 12325.568605098779, 12327.0, 12606.243625382507, 12798.424070916644, 12034.578789742318, 12116.27302361861, 12242.75514489723, 12292.508299667914, 12345.567905123278, 12982.675433113869, 12996.675083122998, 13029.283389413673, 13042.478300868239, 13049.608511744664, 11578.76842463163, 11608.0, 11731.824022639837, 11841.940790295994, 11866.110041747077], "value": 9412.650163863833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.140547941099, 1500.6923580665962, 1522.8857835662325, 1677.3626022111596, 1835.4952388093263, 8235.299999500043, 8490.320774338054, 8510.276626486757, 8727.0836562161, 8731.345149113826, 8552.401331906769, 8704.564771761417, 8719.5640217989, 8733.825323493542, 8736.43213191142, 2000.5098750806, 2231.654093615492, 2310.803581695558, 2314.872682002489, 2341.8594884306913, 2275.7496675365696, 2321.071571371448, 2325.5232677301196, 2327.359976006603, 2328.3364241191266, 2250.8762018089, 2282.2696737044153, 2287.977120228793, 2291.472961218921, 2291.8281128915314, 2008.417558907915, 2217.789310015554, 2278.9544209115725, 2287.2566415914894, 2306.261996161229, 2292.9541409171725, 2319.652052192168, 2325.837191396603, 2327.8603283803027, 2331.778481044315, 8883.955580222122, 8893.599788009491, 8911.153440423213, 8913.643454261823, 8942.508162051085, 5266.236395722637, 5400.243965844785, 5411.242426060355, 5414.458554144593, 5414.512693857532, 3210.0851257391764, 3307.9503807442907, 3308.2556424804466, 3339.5491608632765, 3397.2356219850585, 1021.7190272675, 1026.5329275179774, 1031.742064483879, 1064.387976913278, 1071.164491696478, 12440.688982775413, 12702.237865728086, 12711.364431778375, 12713.745725085539, 12714.61856144317, 4824.421069471616, 4869.14789911764, 4929.0, 4938.209886418156, 4947.307376967227, 10770.946145269223, 10782.676519704264, 10796.298240614493, 10800.027997480265, 10809.189310801721, 4696.365990591295, 5452.863678408072, 5510.614257001971, 5511.58663100269, 5525.309336332958, 4890.315355850184, 5023.020511000393, 5029.547340739351, 5065.746712664352, 5066.949330506722, 4128.298189307825, 4163.854265100707, 4171.165766846582, 4220.894477638022, 4227.556106608823, 1302.693866941268, 1320.8150858879765, 1329.8071779591905, 1332.4403750424844, 1350.1763924006348, 913.6984795017628, 988.3032462114193, 991.2516050381915, 1005.7988402319565, 1013.4122209118693, 5880.411958804171, 6031.638101713911, 6059.151718759377, 6110.358412366726, 6197.96901015501, 486.4770371850275, 493.279811475243, 498.2451585847444, 502.57030239145735, 502.6581924291517, 3979.9204015919518, 4076.7961601919787, 4125.608067233608, 4148.543660197376, 4164.8542301019925, 10924.726881827859, 11200.991910728073, 11201.495932683049, 11224.943875280735, 11275.37985410794, 10595.682129535971, 11050.557977680963, 11144.33134011962, 11222.214444988937, 11237.550497980154, 8329.08380078191, 8413.495190288602, 8430.915690843012, 8504.31965442763, 8518.574071296534, 5903.203067585992, 6084.695765211633, 6223.813285601439, 6247.812565623039, 6272.686365681789, 9854.063863932915, 10348.120409764953, 10463.267571269938, 10472.319299245524, 10476.68569942903, 10223.693289201336, 10418.166546676097, 10442.63450779219, 10689.625863095065, 10691.839622405514, 6738.35985581369, 6924.030635710904, 6924.757633482804, 7796.454248202798, 7969.641366138421, 8019.278264956182, 8509.36179786531, 8801.691940782042, 8807.823843523222, 9012.41419307743, 7211.567305961659, 7398.519096258726, 7521.135069466863, 7851.960740196262, 8101.270885620323, 7315.48791584584, 7339.633018349168, 7339.816504587428, 7472.0, 7481.438892083224, 6709.396154346132, 6742.797716068526, 6774.762883299061, 6776.085228494288, 6781.491388145811, 4984.925226121683, 5022.472640372708, 5145.279660847559, 5181.715005674724, 5203.687778733288, 4984.327115839315, 5024.899502010013, 5057.46896575869, 5145.176771716508, 5168.560672342893], "value": 4578.26473020998, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.1732070635288, 320.693737480706, 320.7097576693093, 328.0764647517238, 328.56465183631695, 5300.337457817773, 5313.601479889009, 5332.226827110067, 5349.598780091489, 5385.946140538594, 5230.6600070995355, 5242.7640756165965, 5246.658967167132, 5248.921266181011, 5292.153255479124, 4058.2492238935743, 4126.174765046988, 4128.607782260696, 4128.814203360856, 4139.068709540352, 4118.320477121271, 4134.875953721378, 4146.66826653867, 4149.605787450188, 4152.647025002879, 4036.0717035081802, 4068.16602596467, 4081.571434999312, 4083.0, 4091.3862920561855, 3881.9805900970596, 3920.5883382245024, 4045.939310910339, 4060.776657283848, 4094.0, 3572.160542272564, 3573.714102871764, 3579.3020361029608, 3590.874319398835, 3599.6040435552068, 5062.594992400599, 5066.240063990394, 5067.822626208102, 5069.771860266297, 5109.310243117169, 4451.465824101097, 4547.5452454754595, 4597.264437689953, 4629.305604159369, 4697.0, 5470.9452905470835, 5496.642718223303, 5510.889782204374, 5516.806911758109, 5524.944750552483, 3933.095388060762, 3945.8027098644952, 3983.920321593581, 3995.5005624296964, 4002.0995276062945, 5586.13414920688, 5645.350784659776, 5688.175214593902, 5695.686737229451, 5706.715988902505, 4001.919961600752, 4024.3762216856435, 4026.3356546169553, 4034.8298993292206, 4038.5557588665224, 4081.4490043844294, 4095.0, 4098.200850834119, 4121.093359460914, 4169.082801783603, 4337.327714204303, 4584.15193189261, 4594.402727645384, 4732.0, 4748.264019077049, 5442.755076021588, 5462.453754624506, 5470.604995726144, 5492.313460817398, 5505.449455054542, 1377.937992790327, 1386.3899884051186, 1393.4602264497787, 1393.5888912770756, 1394.5258612071943, 853.902734985553, 877.3330671723776, 893.7363477774072, 893.8380105862353, 900.2348004196512, 4744.667873248839, 4789.233722604366, 4791.616670666408, 4796.424429068465, 4830.758462076952, 4976.531923082627, 4999.52504512078, 5030.245463180552, 5037.186612819382, 5082.110630639623, 4843.927341089887, 4853.708777473363, 4859.757012149378, 5137.434882162959, 5211.609129315317, 2916.343822639889, 3015.20097174249, 3019.064090132066, 3027.8031927924612, 3036.30165062037, 5865.445656900924, 5887.882242355129, 5895.056790913519, 5910.172575839386, 5921.0, 5517.2551705520045, 5718.25662663851, 5750.613495399446, 5758.452946970031, 5767.250257466502, 5859.384764599741, 5901.232839730807, 5916.171735956969, 5922.963481390825, 5929.288485381781, 5385.27298814655, 5701.828945131652, 5705.830304787426, 5715.199872018004, 5723.3418156912485, 5609.27079479665, 5639.830805075854, 5658.708223169623, 5659.85850353728, 5660.745266463102, 5850.5904586678525, 5905.704714764331, 5935.584509084322, 5944.484156540055, 5957.910631340446, 3725.4970578971493, 3922.6861851052417, 3923.5684074752326, 3999.3201155804163, 4011.3553443088194, 5633.38032816398, 5641.435856414326, 5676.631018983753, 5679.290088738908, 5687.630304030225, 5532.474414930575, 5569.916451253315, 5570.275864137636, 5596.608237423341, 5608.691521966168, 5371.4897084776885, 5457.262402869161, 5504.80733174337, 5517.523959905234, 5532.612717109924, 5391.7304134793885, 5412.0, 5418.187271909245, 5435.456454354533, 5444.782208711607, 5345.685674881598, 5410.918836217538, 5486.725663716878, 5532.612717109763, 5579.944200558105, 5374.495141360425, 5393.244945707525, 5419.485148910847, 5437.374701909331, 5456.9618482323585], "value": 4160.983229000512, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.3264821665249, 754.4304050441915, 762.816923938255, 775.4959276470297, 793.4445887878483, 11223.270487418313, 12662.303573303465, 13267.402966866503, 13673.521426750065, 13837.100588461743, 13372.732545349087, 13818.170909745397, 13887.652808679788, 13922.582322530341, 13933.094348867317, 679.7009315900988, 822.3626689315784, 823.5964377455064, 829.2412442615, 829.3655353654459, 669.7990602819159, 692.2973182220051, 798.0861913109486, 811.930985866202, 812.6383759227147, 865.5066612031145, 866.4714524140269, 866.9349798765086, 870.1994165367845, 870.8911386076741, 861.9741407757778, 862.9137086291383, 864.0581765875163, 864.4553931023446, 864.8183881384916, 628.4249911331126, 630.014028046111, 630.0549176235646, 630.1492984470973, 630.5145038320468, 12912.677183070498, 12925.741485170338, 12931.224126552437, 12963.157394769403, 12971.35143242834, 12953.740925181537, 12991.870081299161, 13009.544665936646, 13056.932224956281, 13068.607941761762, 10674.0, 10722.678319650424, 10910.890891091067, 11075.50160242791, 11189.384583847885, 3762.5673047599603, 3764.698824094066, 3841.0, 3860.7104467165077, 3934.390169523728, 54037.0, 55648.16527752127, 55703.0, 55742.44257557413, 56218.43781562173, 10497.527611257512, 10570.471476426148, 10590.86286318222, 10597.41714205726, 10876.238663293652, 17958.102094895203, 19019.42941711751, 19027.14377853, 19044.61910761777, 19119.426417207505, 8326.29226515753, 8327.958360208282, 8328.8334233315, 8392.496450213006, 8571.57142142903, 4214.7471151731, 4607.746573938465, 4657.324687920301, 4769.260764581426, 4994.875128121826, 3249.918752031218, 3436.7078798301923, 3438.1404648837793, 3477.4609935460044, 3477.791332520057, 650.9674516274167, 660.86452277284, 664.2659860853687, 673.9865202696017, 675.4663815585731, 657.983550411234, 676.3658722838742, 752.9322360987537, 756.9772906812805, 769.9730509432143, 8968.955155224312, 9054.194698550504, 9143.954280228556, 9279.67521136757, 9418.058194180527, 593.6942474625571, 644.9677516124175, 672.640137526421, 673.3636713305879, 679.4496457869142, 4260.318349064197, 4397.428334316518, 4441.111777644484, 4443.955560444419, 4472.351509031172, 73955.63022184963, 74939.0, 75473.0, 76611.0, 80056.99572531966, 48642.513574864504, 54566.45433545693, 61736.0, 67259.0, 68980.3101968984, 59221.40778592245, 62656.0, 63406.0, 63486.0, 63930.6803465989, 8566.100559441169, 8611.397202195787, 8668.523231222343, 8694.782630434289, 8785.868211976953, 39950.80024599859, 40377.0, 40460.3930941042, 40514.59485405108, 40875.1824963505, 52245.477545225585, 54684.17973730477, 55018.17472737832, 55907.44092559022, 56022.43977560172, 11200.887991120311, 11238.0, 11246.156538259827, 11375.31748095117, 11842.822357664814, 11400.0, 11624.767504650028, 11986.520539178335, 12026.879731202926, 12541.372931353213, 11173.580105260597, 11529.711757206136, 11713.824292635447, 11941.481195336763, 12086.456109474919, 11117.888821111685, 11302.660920172408, 11436.599719009795, 11457.197996140523, 11570.59502917394, 11148.721281968015, 11403.144764142557, 11411.0, 11505.654830355103, 11544.845515448387, 8673.956630216808, 8706.1729135732, 8746.562671866508, 9009.54952252398, 9088.863667045132, 8786.121387861163, 9430.669926552539, 9661.661841835503, 9664.56509457062, 9669.758256043655], "value": 6677.270826917344, "unit": "score"}], "name": "dromaeo_css", "value": 7087.120909020671, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 109.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 127.0, 180.0, 130.0, 126.0, 151.0, 127.0, 154.0, 130.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 160.0, 163.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 126.0, 74.0, 75.0, 73.0, 74.0, 102.0, 73.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 114.0, 83.0, 84.0, 84.0, 110.0, 85.0, 84.0, 128.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.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": [55.0, 53.0, 53.0, 53.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 66.0, 63.0, 63.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 78.0, 78.0, 83.0, 83.0, 90.0, 81.0, 120.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 122.0, 119.0, 118.0, 128.0, 117.0, 126.0, 120.0, 134.0, 133.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 156.0, 156.0, 157.0, 161.0, 164.0, 162.0, 158.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 67.0, 61.0, 62.0, 63.0, 62.0, 62.0, 60.0, 65.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1491.7000000000003, "alertThreshold": 2.0}]} 09:00:17 INFO - Return code: 0 09:00:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:00:17 ERROR - # TBPL SUCCESS # 09:00:17 INFO - Running post-action listener: _resource_record_post_action 09:00:17 INFO - ##### 09:00:17 INFO - ##### Finished run-tests step (success) 09:00:17 INFO - ##### 09:00:17 INFO - Running post-run listener: _resource_record_post_run 09:00:17 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 12288; Write bytes: 543268864; Read time: 120; Write time: 448392 09:00:17 INFO - TinderboxPrint: CPU usage
13.1% 09:00:17 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 120 09:00:17 INFO - TinderboxPrint: I/O write bytes / time
543,268,864 / 448,392 09:00:17 INFO - TinderboxPrint: CPU idle
8,272.1 (86.9%) 09:00:17 INFO - TinderboxPrint: CPU user
1,204.6 (12.7%) 09:00:17 INFO - TinderboxPrint: Swap in / out
0 / 0 09:00:17 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247345152; Read time: 0; Write time: 276224 09:00:17 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 12288; Write bytes: 284327936; Read time: 120; Write time: 169228 09:00:18 INFO - Running post-run listener: _upload_blobber_files 09:00:18 INFO - Blob upload gear active. 09:00:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:00:18 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 09:00:18 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:00:18 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:00:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:00:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:00:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:00:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:19 INFO - (blobuploader) - INFO - Done attempting. 09:00:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:00:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:00:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:23 INFO - (blobuploader) - INFO - Done attempting. 09:00:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:00:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:00:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:23 INFO - (blobuploader) - INFO - Done attempting. 09:00:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:00:23 INFO - Return code: 0 09:00:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:00:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:00:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/797a5b7303fb4b30969cc99389355a479fbebe8476d07a032b458533cebd92f7a7f0cc41a24d22f81681d848498e70f2e01416b397d22f0e5c20b34df7e14acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82a7ffc1bad77bdf9126311ad240cc2bcf9be13939a32adc185097f248bf8697877ac9d9d4541c4819dff52cb56b03a386e87803a9a1d35c24f229853ce394f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2507e7505372a4747b4f17bceeb9d798ebb5dd111872419e638b36ab2b70b4a35e33dae97d3f91ea8ab9bf72f9f98435627a8770a209075b194a55f346348a90"} 09:00:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:00:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:00:23 INFO - Contents: 09:00:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/797a5b7303fb4b30969cc99389355a479fbebe8476d07a032b458533cebd92f7a7f0cc41a24d22f81681d848498e70f2e01416b397d22f0e5c20b34df7e14acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82a7ffc1bad77bdf9126311ad240cc2bcf9be13939a32adc185097f248bf8697877ac9d9d4541c4819dff52cb56b03a386e87803a9a1d35c24f229853ce394f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2507e7505372a4747b4f17bceeb9d798ebb5dd111872419e638b36ab2b70b4a35e33dae97d3f91ea8ab9bf72f9f98435627a8770a209075b194a55f346348a90"} 09:00:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:00:23 INFO - Copying logs to upload dir... 09:00:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:00:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.617488 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-05-30 09:00:23.641254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 09:00:23.645235) ========= 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=4300f881736b422476174874000001bc-1464622788.508004-1165943564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/797a5b7303fb4b30969cc99389355a479fbebe8476d07a032b458533cebd92f7a7f0cc41a24d22f81681d848498e70f2e01416b397d22f0e5c20b34df7e14acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82a7ffc1bad77bdf9126311ad240cc2bcf9be13939a32adc185097f248bf8697877ac9d9d4541c4819dff52cb56b03a386e87803a9a1d35c24f229853ce394f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2507e7505372a4747b4f17bceeb9d798ebb5dd111872419e638b36ab2b70b4a35e33dae97d3f91ea8ab9bf72f9f98435627a8770a209075b194a55f346348a90"} build_url:https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/bG1KpJ7zTiuA3vKosVJBdA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/797a5b7303fb4b30969cc99389355a479fbebe8476d07a032b458533cebd92f7a7f0cc41a24d22f81681d848498e70f2e01416b397d22f0e5c20b34df7e14acd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82a7ffc1bad77bdf9126311ad240cc2bcf9be13939a32adc185097f248bf8697877ac9d9d4541c4819dff52cb56b03a386e87803a9a1d35c24f229853ce394f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2507e7505372a4747b4f17bceeb9d798ebb5dd111872419e638b36ab2b70b4a35e33dae97d3f91ea8ab9bf72f9f98435627a8770a209075b194a55f346348a90"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 09:00:23.674660) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:00:23.675131) ========= 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=4300f881736b422476174874000001bc-1464622788.508004-1165943564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004430 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:00:23.758633) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 09:00:23.758969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 09:00:23.759274) ========= ========= Total master_lag: 0.17 =========