builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-051 starttime: 1463723957.84 results: success (0) buildid: 20160519203032 builduid: fb0f5803510540ada2ec95aa72d5d836 revision: c62705a833ff9c985c71ddf4c07054de113525c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:17.842286) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:17.843122) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:17.843513) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:17.895515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:17.895822) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 22:59:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 42.9M=0s 2016-05-19 22:59:18 (42.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128219 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.044422) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.044790) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018280 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.095263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.095632) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c62705a833ff9c985c71ddf4c07054de113525c3 --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 c62705a833ff9c985c71ddf4c07054de113525c3 --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=5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 22:59:18,713 truncating revision to first 12 chars 2016-05-19 22:59:18,713 Setting DEBUG logging. 2016-05-19 22:59:18,713 attempt 1/10 2016-05-19 22:59:18,713 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c62705a833ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 22:59:19,221 unpacking tar archive at: fx-team-c62705a833ff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.785398 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.896717) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.897041) ========= script_repo_revision: c62705a833ff9c985c71ddf4c07054de113525c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.897447) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.897776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 22:59:18.907817) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-19 22:59:18.908121) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:59:19 INFO - MultiFileLogger online at 20160519 22:59:19 in /builds/slave/test 22:59:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:59:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:59:19 INFO - {'append_to_log': False, 22:59:19 INFO - 'base_work_dir': '/builds/slave/test', 22:59:19 INFO - 'blob_upload_branch': 'Fx-Team', 22:59:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:59:19 INFO - 'branch': 'Fx-Team', 22:59:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:59:19 INFO - 'config_files': ('talos/linux_config.py',), 22:59:19 INFO - 'default_actions': ('clobber', 22:59:19 INFO - 'read-buildbot-config', 22:59:19 INFO - 'download-and-extract', 22:59:19 INFO - 'populate-webroot', 22:59:19 INFO - 'create-virtualenv', 22:59:19 INFO - 'install', 22:59:19 INFO - 'run-tests'), 22:59:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:59:19 INFO - 'download_minidump_stackwalk': True, 22:59:19 INFO - 'download_symbols': 'ondemand', 22:59:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:59:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:59:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:59:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:59:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:59:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:59:19 INFO - 'installer_path': 'installer.exe', 22:59:19 INFO - 'log_level': 'info', 22:59:19 INFO - 'log_name': 'talos', 22:59:19 INFO - 'log_to_console': True, 22:59:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:59:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:59:19 INFO - 'opt_config_files': (), 22:59:19 INFO - 'pip_index': False, 22:59:19 INFO - 'sps_profile': False, 22:59:19 INFO - 'sps_profile_interval': 0, 22:59:19 INFO - 'suite': 'dromaeojs-e10s', 22:59:19 INFO - 'system_bits': '32', 22:59:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:59:19 INFO - 'title': 'talos-linux64-ix-051', 22:59:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:59:19 INFO - 'use_talos_json': True, 22:59:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:59:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:59:19 INFO - 'work_dir': 'build'} 22:59:19 INFO - ##### 22:59:19 INFO - ##### Running clobber step. 22:59:19 INFO - ##### 22:59:19 INFO - Running pre-action listener: _resource_record_pre_action 22:59:19 INFO - Running main action method: clobber 22:59:19 INFO - rmtree: /builds/slave/test/build 22:59:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:59:20 INFO - Running post-action listener: _resource_record_post_action 22:59:20 INFO - ##### 22:59:20 INFO - ##### Finished clobber step (success) 22:59:20 INFO - ##### 22:59:20 INFO - ##### 22:59:20 INFO - ##### Running read-buildbot-config step. 22:59:20 INFO - ##### 22:59:20 INFO - Running pre-action listener: _resource_record_pre_action 22:59:20 INFO - Running main action method: read_buildbot_config 22:59:20 INFO - Using buildbot properties: 22:59:20 INFO - { 22:59:20 INFO - "project": "", 22:59:20 INFO - "product": "firefox", 22:59:20 INFO - "script_repo_revision": "production", 22:59:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 22:59:20 INFO - "repository": "", 22:59:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 22:59:20 INFO - "buildid": "20160519203032", 22:59:20 INFO - "pgo_build": "True", 22:59:20 INFO - "basedir": "/builds/slave/test", 22:59:20 INFO - "buildnumber": 353, 22:59:20 INFO - "slavename": "talos-linux64-ix-051", 22:59:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:59:20 INFO - "platform": "ubuntu64_hw", 22:59:20 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:59:20 INFO - "branch": "fx-team", 22:59:20 INFO - "repo_path": "integration/fx-team", 22:59:20 INFO - "revision": "c62705a833ff9c985c71ddf4c07054de113525c3", 22:59:20 INFO - "stage_platform": "linux64-pgo", 22:59:20 INFO - "builduid": "fb0f5803510540ada2ec95aa72d5d836", 22:59:20 INFO - "slavebuilddir": "test" 22:59:20 INFO - } 22:59:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:59:20 INFO - Running post-action listener: _resource_record_post_action 22:59:20 INFO - ##### 22:59:20 INFO - ##### Finished read-buildbot-config step (success) 22:59:20 INFO - ##### 22:59:20 INFO - ##### 22:59:20 INFO - ##### Running download-and-extract step. 22:59:20 INFO - ##### 22:59:20 INFO - Running pre-action listener: _resource_record_pre_action 22:59:20 INFO - Running main action method: download_and_extract 22:59:20 INFO - mkdir: /builds/slave/test/build/tests 22:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:20 INFO - https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:59:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:59:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:59:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:59:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:59:29 INFO - Downloaded 1361 bytes. 22:59:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:59:29 INFO - Using the following test package requirements: 22:59:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:59:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:59:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'jsshell-linux-x86_64.zip'], 22:59:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:59:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:59:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:59:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:59:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:59:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:29 INFO - https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:59:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:59:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:59:32 INFO - Downloaded 24387668 bytes. 22:59:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:59:34 INFO - Return code: 0 22:59:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:34 INFO - https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:59:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:59:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:59:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:59:40 INFO - Downloaded 24387668 bytes. 22:59:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:59:42 INFO - Return code: 0 22:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:42 INFO - https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:59:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:59:43 INFO - Downloaded 10840359 bytes. 22:59:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:59:43 INFO - Return code: 0 22:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:43 INFO - https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:59:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:59:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:59:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:59:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:59:45 INFO - Downloaded 58565734 bytes. 22:59:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:59:45 INFO - mkdir: /builds/slave/test/properties 22:59:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:59:45 INFO - Writing to file /builds/slave/test/properties/build_url 22:59:45 INFO - Contents: 22:59:45 INFO - build_url:https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:59:45 INFO - Running post-action listener: _resource_record_post_action 22:59:45 INFO - Running post-action listener: set_extra_try_arguments 22:59:45 INFO - ##### 22:59:45 INFO - ##### Finished download-and-extract step (success) 22:59:45 INFO - ##### 22:59:45 INFO - ##### 22:59:45 INFO - ##### Running populate-webroot step. 22:59:45 INFO - ##### 22:59:45 INFO - Running pre-action listener: _resource_record_pre_action 22:59:45 INFO - Running main action method: populate_webroot 22:59:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:59:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:59:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:59:45 INFO - '--tppagecycles', 22:59:45 INFO - '7'], 22:59:45 INFO - 'tests': ['tsvgm']}}, 22:59:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:59:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:59:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:59:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:59:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['damp', 'tps']}, 22:59:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'tests': ['damp', 'tps']}, 22:59:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['dromaeo_dom']}, 22:59:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:59:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['a11yr', 22:59:45 INFO - 'ts_paint', 22:59:45 INFO - 'tpaint', 22:59:45 INFO - 'sessionrestore', 22:59:45 INFO - 'sessionrestore_no_auto_restore', 22:59:45 INFO - 'tabpaint']}, 22:59:45 INFO - 'other-e10s': {'tests': ['a11yr', 22:59:45 INFO - 'ts_paint', 22:59:45 INFO - 'tpaint', 22:59:45 INFO - 'sessionrestore', 22:59:45 INFO - 'sessionrestore_no_auto_restore', 22:59:45 INFO - 'tabpaint']}, 22:59:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:59:45 INFO - 'ts_paint', 22:59:45 INFO - 'tpaint', 22:59:45 INFO - 'sessionrestore', 22:59:45 INFO - 'sessionrestore_no_auto_restore', 22:59:45 INFO - 'tabpaint']}, 22:59:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:59:45 INFO - 'ts_paint', 22:59:45 INFO - 'tpaint', 22:59:45 INFO - 'sessionrestore', 22:59:45 INFO - 'sessionrestore_no_auto_restore', 22:59:45 INFO - 'tabpaint']}, 22:59:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['a11yr', 22:59:45 INFO - 'ts_paint', 22:59:45 INFO - 'tpaint', 22:59:45 INFO - 'sessionrestore', 22:59:45 INFO - 'sessionrestore_no_auto_restore', 22:59:45 INFO - 'tabpaint']}, 22:59:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['a11yr', 22:59:45 INFO - 'ts_paint', 22:59:45 INFO - 'tpaint', 22:59:45 INFO - 'sessionrestore', 22:59:45 INFO - 'sessionrestore_no_auto_restore', 22:59:45 INFO - 'tabpaint']}, 22:59:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['tsvgx', 22:59:45 INFO - 'tsvgr_opacity', 22:59:45 INFO - 'tart', 22:59:45 INFO - 'tscrollx', 22:59:45 INFO - 'cart']}, 22:59:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:59:45 INFO - 'tsvgr_opacity', 22:59:45 INFO - 'tart', 22:59:45 INFO - 'tscrollx', 22:59:45 INFO - 'cart']}, 22:59:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'talos_options': ['--disable-e10s'], 22:59:45 INFO - 'tests': ['tp5o']}, 22:59:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'tests': ['tp5o']}, 22:59:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'talos_options': ['--disable-e10s', 22:59:45 INFO - '--xperf_path', 22:59:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:45 INFO - 'tests': ['tp5n']}, 22:59:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:45 INFO - 'talos_options': ['--xperf_path', 22:59:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:45 INFO - 'tests': ['tp5n']}}, 22:59:45 INFO - 'talos.zip': {'path': '', 22:59:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:59:45 INFO - Running post-action listener: _resource_record_post_action 22:59:45 INFO - ##### 22:59:45 INFO - ##### Finished populate-webroot step (success) 22:59:45 INFO - ##### 22:59:45 INFO - ##### 22:59:45 INFO - ##### Running create-virtualenv step. 22:59:45 INFO - ##### 22:59:45 INFO - Running pre-action listener: _resource_record_pre_action 22:59:45 INFO - Running main action method: create_virtualenv 22:59:45 INFO - Creating virtualenv /builds/slave/test/build/venv 22:59:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:59:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:59:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:59:46 INFO - Using real prefix '/usr' 22:59:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:59:46 INFO - Installing distribute.............................................................................................................................................................................................done. 22:59:48 INFO - Installing pip.................done. 22:59:48 INFO - Return code: 0 22:59:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:59:48 INFO - Using env: {'DISPLAY': ':0', 22:59:48 INFO - 'HOME': '/home/cltbld', 22:59:48 INFO - 'LANG': 'en_US.UTF-8', 22:59:48 INFO - 'LANGUAGE': 'en_US:en', 22:59:48 INFO - 'LOGNAME': 'cltbld', 22:59:48 INFO - 'MAIL': '/var/mail/cltbld', 22:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:48 INFO - 'MOZ_NO_REMOTE': '1', 22:59:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:48 INFO - 'NO_EM_RESTART': '1', 22:59:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:48 INFO - 'PWD': '/builds/slave/test', 22:59:48 INFO - 'SHELL': '/bin/bash', 22:59:48 INFO - 'SHLVL': '1', 22:59:48 INFO - 'TERM': 'linux', 22:59:48 INFO - 'TMOUT': '86400', 22:59:48 INFO - 'USER': 'cltbld', 22:59:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 22:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:48 INFO - '_': '/tools/buildbot/bin/python'} 22:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:59:48 INFO - Cleaning up... 22:59:48 INFO - Return code: 0 22:59:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:59:48 INFO - Using env: {'DISPLAY': ':0', 22:59:48 INFO - 'HOME': '/home/cltbld', 22:59:48 INFO - 'LANG': 'en_US.UTF-8', 22:59:48 INFO - 'LANGUAGE': 'en_US:en', 22:59:48 INFO - 'LOGNAME': 'cltbld', 22:59:48 INFO - 'MAIL': '/var/mail/cltbld', 22:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:48 INFO - 'MOZ_NO_REMOTE': '1', 22:59:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:48 INFO - 'NO_EM_RESTART': '1', 22:59:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:48 INFO - 'PWD': '/builds/slave/test', 22:59:48 INFO - 'SHELL': '/bin/bash', 22:59:48 INFO - 'SHLVL': '1', 22:59:48 INFO - 'TERM': 'linux', 22:59:48 INFO - 'TMOUT': '86400', 22:59:48 INFO - 'USER': 'cltbld', 22:59:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 22:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:48 INFO - '_': '/tools/buildbot/bin/python'} 22:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:48 INFO - Downloading/unpacking psutil>=3.1.1 22:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:59:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:50 INFO - Installing collected packages: psutil 22:59:50 INFO - Running setup.py install for psutil 22:59:51 INFO - building 'psutil._psutil_linux' extension 22:59:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:59:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:59:51 INFO - building 'psutil._psutil_posix' extension 22:59:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:59:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:59:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:51 INFO - Successfully installed psutil 22:59:51 INFO - Cleaning up... 22:59:51 INFO - Return code: 0 22:59:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:59:51 INFO - Using env: {'DISPLAY': ':0', 22:59:51 INFO - 'HOME': '/home/cltbld', 22:59:51 INFO - 'LANG': 'en_US.UTF-8', 22:59:51 INFO - 'LANGUAGE': 'en_US:en', 22:59:51 INFO - 'LOGNAME': 'cltbld', 22:59:51 INFO - 'MAIL': '/var/mail/cltbld', 22:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:51 INFO - 'MOZ_NO_REMOTE': '1', 22:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:51 INFO - 'NO_EM_RESTART': '1', 22:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:51 INFO - 'PWD': '/builds/slave/test', 22:59:51 INFO - 'SHELL': '/bin/bash', 22:59:51 INFO - 'SHLVL': '1', 22:59:51 INFO - 'TERM': 'linux', 22:59:51 INFO - 'TMOUT': '86400', 22:59:51 INFO - 'USER': 'cltbld', 22:59:51 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 22:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:51 INFO - '_': '/tools/buildbot/bin/python'} 22:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:59:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:59:53 INFO - Installing collected packages: mozsystemmonitor 22:59:53 INFO - Running setup.py install for mozsystemmonitor 22:59:53 INFO - Successfully installed mozsystemmonitor 22:59:53 INFO - Cleaning up... 22:59:53 INFO - Return code: 0 22:59:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:53 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 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:59:53 INFO - Using env: {'DISPLAY': ':0', 22:59:53 INFO - 'HOME': '/home/cltbld', 22:59:53 INFO - 'LANG': 'en_US.UTF-8', 22:59:53 INFO - 'LANGUAGE': 'en_US:en', 22:59:53 INFO - 'LOGNAME': 'cltbld', 22:59:53 INFO - 'MAIL': '/var/mail/cltbld', 22:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:53 INFO - 'MOZ_NO_REMOTE': '1', 22:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:53 INFO - 'NO_EM_RESTART': '1', 22:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:53 INFO - 'PWD': '/builds/slave/test', 22:59:53 INFO - 'SHELL': '/bin/bash', 22:59:53 INFO - 'SHLVL': '1', 22:59:53 INFO - 'TERM': 'linux', 22:59:53 INFO - 'TMOUT': '86400', 22:59:53 INFO - 'USER': 'cltbld', 22:59:53 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 22:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:53 INFO - '_': '/tools/buildbot/bin/python'} 22:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:53 INFO - Downloading/unpacking blobuploader==1.2.4 22:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:55 INFO - Downloading blobuploader-1.2.4.tar.gz 22:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:59:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:59:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:56 INFO - Downloading docopt-0.6.1.tar.gz 22:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:59:56 INFO - Installing collected packages: blobuploader, requests, docopt 22:59:56 INFO - Running setup.py install for blobuploader 22:59:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:59:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:59:56 INFO - Running setup.py install for requests 22:59:57 INFO - Running setup.py install for docopt 22:59:57 INFO - Successfully installed blobuploader requests docopt 22:59:57 INFO - Cleaning up... 22:59:57 INFO - Return code: 0 22:59:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:59:57 INFO - Using env: {'DISPLAY': ':0', 22:59:57 INFO - 'HOME': '/home/cltbld', 22:59:57 INFO - 'LANG': 'en_US.UTF-8', 22:59:57 INFO - 'LANGUAGE': 'en_US:en', 22:59:57 INFO - 'LOGNAME': 'cltbld', 22:59:57 INFO - 'MAIL': '/var/mail/cltbld', 22:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:57 INFO - 'MOZ_NO_REMOTE': '1', 22:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:57 INFO - 'NO_EM_RESTART': '1', 22:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:57 INFO - 'PWD': '/builds/slave/test', 22:59:57 INFO - 'SHELL': '/bin/bash', 22:59:57 INFO - 'SHLVL': '1', 22:59:57 INFO - 'TERM': 'linux', 22:59:57 INFO - 'TMOUT': '86400', 22:59:57 INFO - 'USER': 'cltbld', 22:59:57 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 22:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:57 INFO - '_': '/tools/buildbot/bin/python'} 22:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:57 INFO - Running setup.py (path:/tmp/pip-Wh1vNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:57 INFO - Running setup.py (path:/tmp/pip-1vw5f0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:57 INFO - Running setup.py (path:/tmp/pip-wjDHwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:57 INFO - Running setup.py (path:/tmp/pip-ViYdnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:57 INFO - Running setup.py (path:/tmp/pip-bnR6V5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:57 INFO - Running setup.py (path:/tmp/pip-hEiGua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:57 INFO - Running setup.py (path:/tmp/pip-TABbUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:57 INFO - Running setup.py (path:/tmp/pip-1z75vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:57 INFO - Running setup.py (path:/tmp/pip-2Ib0rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:58 INFO - Running setup.py (path:/tmp/pip-5lzBzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:58 INFO - Running setup.py (path:/tmp/pip-y4Xp4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:58 INFO - Running setup.py (path:/tmp/pip-H7Uvru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:58 INFO - Running setup.py (path:/tmp/pip-s53yBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:58 INFO - Running setup.py (path:/tmp/pip-xYuxOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:58 INFO - Running setup.py (path:/tmp/pip-u7ilRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:58 INFO - Running setup.py (path:/tmp/pip-e3r_iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:58 INFO - Running setup.py (path:/tmp/pip-FtdmPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:59:58 INFO - Running setup.py install for manifestparser 22:59:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:59:58 INFO - Running setup.py install for mozcrash 22:59:58 INFO - Running setup.py install for mozdebug 22:59:58 INFO - Running setup.py install for mozdevice 22:59:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for mozfile 22:59:59 INFO - Running setup.py install for mozhttpd 22:59:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for mozinfo 22:59:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for mozInstall 22:59:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for mozleak 22:59:59 INFO - Running setup.py install for mozlog 22:59:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for moznetwork 22:59:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for mozprocess 22:59:59 INFO - Running setup.py install for mozprofile 22:59:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:59:59 INFO - Running setup.py install for mozrunner 23:00:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:00:00 INFO - Running setup.py install for mozscreenshot 23:00:00 INFO - Running setup.py install for moztest 23:00:00 INFO - Running setup.py install for mozversion 23:00:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:00:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:00:00 INFO - Cleaning up... 23:00:00 INFO - Return code: 0 23:00:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', '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 23:00:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:00:00 INFO - Using env: {'DISPLAY': ':0', 23:00:00 INFO - 'HOME': '/home/cltbld', 23:00:00 INFO - 'LANG': 'en_US.UTF-8', 23:00:00 INFO - 'LANGUAGE': 'en_US:en', 23:00:00 INFO - 'LOGNAME': 'cltbld', 23:00:00 INFO - 'MAIL': '/var/mail/cltbld', 23:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:00 INFO - 'MOZ_NO_REMOTE': '1', 23:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:00 INFO - 'NO_EM_RESTART': '1', 23:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:00 INFO - 'PWD': '/builds/slave/test', 23:00:00 INFO - 'SHELL': '/bin/bash', 23:00:00 INFO - 'SHLVL': '1', 23:00:00 INFO - 'TERM': 'linux', 23:00:00 INFO - 'TMOUT': '86400', 23:00:00 INFO - 'USER': 'cltbld', 23:00:00 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 23:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:00 INFO - '_': '/tools/buildbot/bin/python'} 23:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:00:00 INFO - Running setup.py (path:/tmp/pip-MJal2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:00:00 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)) 23:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:00:00 INFO - Running setup.py (path:/tmp/pip-8hUJzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:00:00 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)) 23:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:00:00 INFO - Running setup.py (path:/tmp/pip-Fv6gzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:00:00 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)) 23:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:00:00 INFO - Running setup.py (path:/tmp/pip-HjcUXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:00:00 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)) 23:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:00:00 INFO - Running setup.py (path:/tmp/pip-GVgVwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:00:00 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)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:00:01 INFO - Running setup.py (path:/tmp/pip-nc3RkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:00:01 INFO - Running setup.py (path:/tmp/pip-hn8iNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:00:01 INFO - Running setup.py (path:/tmp/pip-q4cfXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:00:01 INFO - Running setup.py (path:/tmp/pip-f53fyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:00:01 INFO - Running setup.py (path:/tmp/pip-jXAVez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:00:01 INFO - Running setup.py (path:/tmp/pip-Socn03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:00:01 INFO - Running setup.py (path:/tmp/pip-auNa5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:00:01 INFO - Running setup.py (path:/tmp/pip-JRd2Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:00:01 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)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:00:01 INFO - Running setup.py (path:/tmp/pip-zGWSnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:00:01 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)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:00:01 INFO - Running setup.py (path:/tmp/pip-QU4gZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:00:01 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)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:00:01 INFO - Running setup.py (path:/tmp/pip-W7XM_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:00:01 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)) 23:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:00:01 INFO - Running setup.py (path:/tmp/pip-mfxzGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:00:01 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)) 23:00:01 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)) 23:00:01 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)) 23:00:01 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)) 23:00:01 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)) 23:00:01 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)) 23:00:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:03 INFO - Downloading blessings-1.6.tar.gz 23:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:00:03 INFO - Installing collected packages: blessings 23:00:03 INFO - Running setup.py install for blessings 23:00:03 INFO - Successfully installed blessings 23:00:03 INFO - Cleaning up... 23:00:04 INFO - Return code: 0 23:00:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:00:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:00:04 INFO - Reading from file tmpfile_stdout 23:00:04 INFO - Current package versions: 23:00:04 INFO - argparse == 1.2.1 23:00:04 INFO - blessings == 1.6 23:00:04 INFO - blobuploader == 1.2.4 23:00:04 INFO - docopt == 0.6.1 23:00:04 INFO - manifestparser == 1.1 23:00:04 INFO - mozInstall == 1.12 23:00:04 INFO - mozcrash == 0.17 23:00:04 INFO - mozdebug == 0.1 23:00:04 INFO - mozdevice == 0.48 23:00:04 INFO - mozfile == 1.2 23:00:04 INFO - mozhttpd == 0.7 23:00:04 INFO - mozinfo == 0.9 23:00:04 INFO - mozleak == 0.1 23:00:04 INFO - mozlog == 3.2 23:00:04 INFO - moznetwork == 0.27 23:00:04 INFO - mozprocess == 0.22 23:00:04 INFO - mozprofile == 0.28 23:00:04 INFO - mozrunner == 6.11 23:00:04 INFO - mozscreenshot == 0.1 23:00:04 INFO - mozsystemmonitor == 0.3 23:00:04 INFO - moztest == 0.7 23:00:04 INFO - mozversion == 1.4 23:00:04 INFO - psutil == 3.1.1 23:00:04 INFO - requests == 1.2.3 23:00:04 INFO - wsgiref == 0.1.2 23:00:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', '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 23:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:00:04 INFO - Using env: {'DISPLAY': ':0', 23:00:04 INFO - 'HOME': '/home/cltbld', 23:00:04 INFO - 'LANG': 'en_US.UTF-8', 23:00:04 INFO - 'LANGUAGE': 'en_US:en', 23:00:04 INFO - 'LOGNAME': 'cltbld', 23:00:04 INFO - 'MAIL': '/var/mail/cltbld', 23:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:04 INFO - 'MOZ_NO_REMOTE': '1', 23:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:04 INFO - 'NO_EM_RESTART': '1', 23:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:04 INFO - 'PWD': '/builds/slave/test', 23:00:04 INFO - 'SHELL': '/bin/bash', 23:00:04 INFO - 'SHLVL': '1', 23:00:04 INFO - 'TERM': 'linux', 23:00:04 INFO - 'TMOUT': '86400', 23:00:04 INFO - 'USER': 'cltbld', 23:00:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 23:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:04 INFO - '_': '/tools/buildbot/bin/python'} 23:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 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)) 23:00:04 INFO - Cleaning up... 23:00:04 INFO - Return code: 0 23:00:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:04 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 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', '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 23:00:04 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 23:00:04 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 23:00:04 INFO - Using env: {'DISPLAY': ':0', 23:00:04 INFO - 'HOME': '/home/cltbld', 23:00:04 INFO - 'LANG': 'en_US.UTF-8', 23:00:04 INFO - 'LANGUAGE': 'en_US:en', 23:00:04 INFO - 'LOGNAME': 'cltbld', 23:00:04 INFO - 'MAIL': '/var/mail/cltbld', 23:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:04 INFO - 'MOZ_NO_REMOTE': '1', 23:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:04 INFO - 'NO_EM_RESTART': '1', 23:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:04 INFO - 'PWD': '/builds/slave/test', 23:00:04 INFO - 'SHELL': '/bin/bash', 23:00:04 INFO - 'SHLVL': '1', 23:00:04 INFO - 'TERM': 'linux', 23:00:04 INFO - 'TMOUT': '86400', 23:00:04 INFO - 'USER': 'cltbld', 23:00:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 23:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:04 INFO - '_': '/tools/buildbot/bin/python'} 23:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:04 INFO - Downloading/unpacking jsonschema 23:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:00:06 INFO - Downloading/unpacking functools32 (from jsonschema) 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00:06 INFO - Downloading functools32-3.2.3-2.tar.gz 23:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:00:06 INFO - warning: no files found matching '*.txt' 23:00:06 INFO - no previously-included directories found matching 'build' 23:00:06 INFO - no previously-included directories found matching 'dist' 23:00:06 INFO - no previously-included directories found matching '.git*' 23:00:06 INFO - Installing collected packages: jsonschema, functools32 23:00:06 INFO - Running setup.py install for functools32 23:00:06 INFO - warning: no files found matching '*.txt' 23:00:06 INFO - no previously-included directories found matching 'build' 23:00:06 INFO - no previously-included directories found matching 'dist' 23:00:06 INFO - no previously-included directories found matching '.git*' 23:00:07 INFO - Successfully installed jsonschema functools32 23:00:07 INFO - Cleaning up... 23:00:07 INFO - Return code: 0 23:00:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3ed670f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21838a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x224e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', '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 23:00:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:00:07 INFO - Using env: {'DISPLAY': ':0', 23:00:07 INFO - 'HOME': '/home/cltbld', 23:00:07 INFO - 'LANG': 'en_US.UTF-8', 23:00:07 INFO - 'LANGUAGE': 'en_US:en', 23:00:07 INFO - 'LOGNAME': 'cltbld', 23:00:07 INFO - 'MAIL': '/var/mail/cltbld', 23:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:07 INFO - 'MOZ_NO_REMOTE': '1', 23:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:07 INFO - 'NO_EM_RESTART': '1', 23:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:07 INFO - 'PWD': '/builds/slave/test', 23:00:07 INFO - 'SHELL': '/bin/bash', 23:00:07 INFO - 'SHLVL': '1', 23:00:07 INFO - 'TERM': 'linux', 23:00:07 INFO - 'TMOUT': '86400', 23:00:07 INFO - 'USER': 'cltbld', 23:00:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 23:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:07 INFO - '_': '/tools/buildbot/bin/python'} 23:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:07 INFO - Downloading/unpacking flake8 23:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:00:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:00:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:00:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:00:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:00:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:00:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:00:09 INFO - no previously-included directories found matching 'docs/_build' 23:00:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:00:09 INFO - Running setup.py install for pep8 23:00:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:00:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:00:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:00:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:00:10 INFO - no previously-included directories found matching 'docs/_build' 23:00:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:00:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:00:10 INFO - Cleaning up... 23:00:10 INFO - Return code: 0 23:00:10 INFO - Running post-action listener: _resource_record_post_action 23:00:10 INFO - Running post-action listener: _start_resource_monitoring 23:00:10 INFO - Starting resource monitoring. 23:00:10 INFO - ##### 23:00:10 INFO - ##### Finished create-virtualenv step (success) 23:00:10 INFO - ##### 23:00:10 INFO - ##### 23:00:10 INFO - ##### Running install step. 23:00:10 INFO - ##### 23:00:10 INFO - Running pre-action listener: _resource_record_pre_action 23:00:10 INFO - Running main action method: install 23:00:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:00:10 INFO - Reading from file tmpfile_stdout 23:00:10 INFO - Detecting whether we're running mozinstall >=1.0... 23:00:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:00:10 INFO - Reading from file tmpfile_stdout 23:00:10 INFO - Output received: 23:00:10 INFO - Usage: mozinstall [options] installer 23:00:10 INFO - Options: 23:00:10 INFO - -h, --help show this help message and exit 23:00:10 INFO - -d DEST, --destination=DEST 23:00:10 INFO - Directory to install application into. [default: 23:00:10 INFO - "/builds/slave/test"] 23:00:10 INFO - --app=APP Application being installed. [default: firefox] 23:00:10 INFO - mkdir: /builds/slave/test/build/application 23:00:10 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'] 23:00:10 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 23:00:27 INFO - Reading from file tmpfile_stdout 23:00:27 INFO - Output received: 23:00:27 INFO - /builds/slave/test/build/application/firefox/firefox 23:00:27 INFO - Running post-action listener: _resource_record_post_action 23:00:27 INFO - ##### 23:00:27 INFO - ##### Finished install step (success) 23:00:27 INFO - ##### 23:00:27 INFO - ##### 23:00:27 INFO - ##### Running run-tests step. 23:00:27 INFO - ##### 23:00:27 INFO - Running pre-action listener: _resource_record_pre_action 23:00:27 INFO - Running main action method: run_tests 23:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:00:27 INFO - Python 2.7.3 23:00:27 INFO - Return code: 0 23:00:27 INFO - grabbing minidump binary from tooltool 23:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:27 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 0x22dd9c0>, '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 0x22de8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22ded50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:00:27 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 23:00:27 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 23:00:27 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 23:00:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:00:27 INFO - Return code: 0 23:00:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:00:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:00:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:00:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:00:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:00:28 INFO - Return code: 0 23:00:28 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:00:28 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:00:28 INFO - Using env: {'DISPLAY': ':0', 23:00:28 INFO - 'HOME': '/home/cltbld', 23:00:28 INFO - 'LANG': 'en_US.UTF-8', 23:00:28 INFO - 'LANGUAGE': 'en_US:en', 23:00:28 INFO - 'LOGNAME': 'cltbld', 23:00:28 INFO - 'MAIL': '/var/mail/cltbld', 23:00:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:00:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:28 INFO - 'MOZ_NO_REMOTE': '1', 23:00:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:28 INFO - 'NO_EM_RESTART': '1', 23:00:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:28 INFO - 'PWD': '/builds/slave/test', 23:00:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:00:28 INFO - 'SHELL': '/bin/bash', 23:00:28 INFO - 'SHLVL': '1', 23:00:28 INFO - 'TERM': 'linux', 23:00:28 INFO - 'TMOUT': '86400', 23:00:28 INFO - 'USER': 'cltbld', 23:00:28 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376', 23:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:28 INFO - '_': '/tools/buildbot/bin/python'} 23:00:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:00:28 INFO - mozversion application_buildid: 20160519203032 23:00:28 INFO - mozversion application_changeset: c62705a833ff9c985c71ddf4c07054de113525c3 23:00:28 INFO - mozversion application_display_name: Nightly 23:00:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:00:28 INFO - mozversion application_name: Firefox 23:00:28 INFO - mozversion application_remotingname: firefox 23:00:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:00:28 INFO - mozversion application_vendor: Mozilla 23:00:28 INFO - mozversion application_version: 49.0a1 23:00:28 INFO - mozversion platform_buildid: 20160519203032 23:00:28 INFO - mozversion platform_changeset: c62705a833ff9c985c71ddf4c07054de113525c3 23:00:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:00:28 INFO - mozversion platform_version: 49.0a1 23:00:28 INFO - using testdate: 1463724028 23:00:28 INFO - actual date: 1463724028 23:00:28 INFO - starting webserver on 'localhost:49927' 23:00:28 INFO - SUITE-START | Running 2 tests 23:00:28 INFO - TEST-START | dromaeo_css 23:00:28 INFO - operating with platform_type : linux_ 23:00:28 INFO - Initialising browser for dromaeo_css test... 23:00:28 INFO - TEST-INFO | started process 21160 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGf5nAp/profile http://localhost:49927/getInfo.html) 23:00:31 INFO - PROCESS | 21160 | 23:00:31 INFO - PROCESS | 21160 | (process:21230): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:00:32 INFO - PROCESS | 21160 | __metrics Screen width/height:1600/1200 23:00:32 INFO - PROCESS | 21160 | colorDepth:24 23:00:32 INFO - PROCESS | 21160 | Browser inner width/height: 1024/697 23:00:32 INFO - PROCESS | 21160 | __metrics 23:00:33 ERROR - PROCESS | 21160 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:00:34 INFO - TEST-INFO | 21160: exit 0 23:00:34 INFO - Browser initialized. 23:00:34 INFO - Running cycle 1/1 for dromaeo_css test... 23:00:34 INFO - TEST-INFO | started process 21281 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGf5nAp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:00:36 INFO - PROCESS | 21281 | 23:00:36 INFO - PROCESS | 21281 | (process:21344): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:00:46 INFO - PROCESS | 21281 | RSS: Main: 164630528 23:00:46 INFO - PROCESS | 21281 | 23:01:35 INFO - PROCESS | 21281 | 1463724095670 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] 23:03:43 INFO - PROCESS | 21281 | Cycle 1(1): loaded http://localhost:49927/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49927/tests/dromaeo/cssquery-ext.html) 23:03:44 INFO - PROCESS | 21281 | RSS: Main: 166440960 23:03:44 INFO - PROCESS | 21281 | 23:06:46 INFO - PROCESS | 21281 | Cycle 1(1): loaded http://localhost:49927/tests/dromaeo/cssquery-ext.html (next: http://localhost:49927/tests/dromaeo/cssquery-jquery.html) 23:06:46 INFO - PROCESS | 21281 | RSS: Main: 166862848 23:06:46 INFO - PROCESS | 21281 | 23:09:48 INFO - PROCESS | 21281 | Cycle 1(1): loaded http://localhost:49927/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49927/tests/dromaeo/cssquery-mootools.html) 23:09:49 INFO - PROCESS | 21281 | RSS: Main: 168697856 23:09:49 INFO - PROCESS | 21281 | 23:12:51 INFO - PROCESS | 21281 | Cycle 1(1): loaded http://localhost:49927/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49927/tests/dromaeo/cssquery-prototype.html) 23:12:51 INFO - PROCESS | 21281 | RSS: Main: 166055936 23:12:51 INFO - PROCESS | 21281 | 23:15:54 INFO - PROCESS | 21281 | Cycle 1(1): loaded http://localhost:49927/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49927/tests/dromaeo/cssquery-yui.html) 23:15:54 INFO - PROCESS | 21281 | RSS: Main: 167374848 23:15:54 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | Cycle 1(1): loaded http://localhost:49927/tests/dromaeo/cssquery-yui.html (next: http://localhost:49927/tests/dromaeo/cssquery-dojo.html) 23:18:57 INFO - PROCESS | 21281 | RSS: Main: 168992768 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | __start_tp_report 23:18:57 INFO - PROCESS | 21281 | _x_x_mozilla_page_load 23:18:57 INFO - PROCESS | 21281 | _x_x_mozilla_page_load_details 23:18:57 INFO - PROCESS | 21281 | |i|pagename|runs| 23:18:57 INFO - PROCESS | 21281 | |0;dojo.html;3122.266267427154;3173.2701478659924;3188.856501457434;3195.217171792911;3202.919927001823;5300.761465734036;5407;5423.105187644034;5460.645058071223;5466.070767969445;5254.395744489385;5289.947100528993;5369.704666243355;5386.48828361306;5426.75579598918;4820.517948205178;4860.173770459013;4903.387076615423;4993.101241776479;4997.875053123665;4830.3237546743485;4934.802607895698;4995.6003519718515;4996.650234483587;5026.472220416858;4755.310479980418;4772.904541909143;4836.782344794493;4897.804087836482;4938.061768264036;4809.470958194595;4830.864746784503;4833.130036593412;4849.515048495157;4898.044881248158;4776.331313616097;4782.426108866924;4783.808647654089;4797.904041919177;4805.6155507559315;5122.1804511278015;5213.035588416117;5254.054270231357;5289.497497737709;5335.653182543122;5680.772769089232;5698.34469036062;5703.54371650267;5712.343080545749;5752.482276595127;5726.055200891889;5819.621724587888;5838.9124163137585;5852.180694702745;5855;5361.571074314045;5387.568994480433;5396.163594642837;5399.136138217867;5416.431274716177;5836.270466191726;5866;5872.970635146797;5887.735051922674;5906;5574.414686457944;5577.2191893135;5664.688442135722;5681.318241811009;5689.516391106719;5688.288963879515;5721.370649228582;5764.625299355528;5765.019946609086;5821.243238378984;5677.290338707661;5684.37471878093;5754.992876246723;5767.394423585547;5770.711464426846;5700.173474846124;5763.366029736726;5766.394528574523;5767.307923049261;5831.475167234969;4328.28583283761;4381.364702118175;4398.538153493901;4399.934000989988;4435.223835828717;3881.7476864003747;3885.2229554089295;3935.035916200492;3935.4687117239378;3942.6845852331744;5468.644538105011;5525.0883604204855;5529.253550770594;5531.3362396512675;5589.74846131925;5770.682612456271;5812.59311848175;5814.069748840166;5816.127580862904;5818.389069147764;5397.325334333208;5466.289382380344;5501.807436739695;5511.889762204733;5519.3652729936575;4948.084604348207;5022.447530771612;5032.119379108642;5036.345275114211;5075.746212689424;5974.133750606138;6009.338972712998;6034.426729460694;6040.003399439043;6046.304674962347;5685.346185188756;5789.131630255327;5811.186433899173;5859.9121013183985;5867.413258674099;5968.731407086605;6011.579189456696;6019.939800602113;6022.698865056817;6054.515638748801;5690.487856092972;5715.2855893013375;5750.769969201185;5753.424657534213;5773.16289138081;5684.516816070681;5756.42435756421;5812.709364531841;5813.302403711582;5843.035899076604;5684.573656975661;5700.173474846207;5704;5715.028445164249;5729.255196824386;5694.658320500617;5696.743646535833;5698.1452782083015;5704.3154821421695;5707.258056452634;5355.089634762177;5373.650712703662;5403.621746477709;5409.945900541102;5446.618736688552;5632.493075623213;5661.801836935688;5686.175504551899;5723.885522289447;5747.137929310469;5647;5651.208830763779;5658;5659.773609055592;5666.2350582670815 23:18:57 INFO - PROCESS | 21281 | |1;ext.html;588.8262962426084;611.0132136599391;614.8186285045911;633.2401118657612;654.365265692279;43769.34345984809;44387.77806110969;44783.552164478344;48125.27812082822;48297.51702482974;46547.534524654744;47300.76349618247;47318.52681473184;47357.7632111839;47357.86889589968;8873.245774109208;8934.821303573925;9006.954965225164;9010.86483702745;9060.909390906105;8895.288376929862;8967.775805604879;8976.461412315282;8991;8998;8968.46189228645;9020.639174433014;9067.138621830949;9069.31980101495;9071.138241867013;8947.284217262604;8981.281497480186;8995.74059631652;9018.639254429814;9050.547472626375;8841.823163536694;8910.287177025823;8963;8964.05877382872;8974.730758077269;43045;43374.78312608448;43377;43606.037728301984;43873.56126438727;3374.409478341305;3416.521686963802;3538.929221415557;3768.510093687803;3872.5075829148395;32472.6752732472;32747;33263.66736332654;33282.33435331304;33317;4321.286987647034;4357.150355680644;4379.474463064421;4388.736675799462;4534.841280555198;345628;346622;347574;349365;349642.99999999744;58856;58929;59398.40601593929;59408;59764.701176493836;65064.67467662632;65254.67372663107;65589;65951;66034;16987.490375288762;17190.398336058177;17198.398056067977;17267.222974966156;17274;13046.608601741962;13077.084604077623;13164.934175329254;13183.736325273441;13222.206667599974;17360.974743082577;18612.627747445247;18910.338138165374;19146.23415063382;19179.712304315446;3195.872165113416;3298.274379636476;3327.072176484678;5994.610350327215;6008.789692360747;1032.6437379104198;1042.5464922758624;1113.142879982409;1153.4358831211596;1231.0889941443268;33004.3399132016;34183.145421364665;34318.65681343205;36083;36510.45234321487;1115.67087709126;1153.4809335798925;1158.7856246594406;1158.8261760735788;1228.011450782129;17358.39245626397;18216.077890401564;19025;19383.515412114528;19448.319308824408;552167;553114;554304;554789;557937;61695;61814;62239.688801555705;62337;62745.37254627486;1367.446184295362;1413.688988422566;1425.287356321839;1436.5833908166514;1459.9781003284959;32760.344793103526;34578.308433831684;34848.128796780285;34894;35096.47355289724;54622;54957;55418.16872746831;55506.167407488116;55558.44441555533;494894;502109;503948;504235;504538;29062.709372906;29349;29643.85178074096;29794.553081703358;29839.552406713483;49588;50162.544874352396;50984.23523647074;51125;51180;43492.34761478517;44002;44150.33774493321;44408;44999.550004499535;42988.78505607577;43016.139677206906;43054.13891722211;43126;43617;42436.15127697367;43125.56874431216;43212.567874320855;43265.783671081444;43299.567004329554;36747.44878826873;38860;39272.21455570815;39679.40480892847;40073.59926400815;29126.27184320409;29326.56010159892;29412.55881161827;29524.852375737984;29849.25376865509 23:18:57 INFO - PROCESS | 21281 | |2;jquery.html;378.2737144682211;379.0920744816165;383.342567496743;397.58452417224;398.14796335841305;15349.69300613987;17315.567110822234;18568.35010774623;20050.498737531587;20050.799492005077;17767.64464710705;18130.093495325247;18173.63652726948;18458.261669533204;18497.16762745676;5093.286939828427;5540.36285827131;5724.570657200706;5739.655620662774;5758.049921762904;5603.551715862742;5606.971965140189;5608.915866261989;5644.322681278252;5647.915281270784;5428.240046393508;5633.63381380211;5641.943580564182;5645.491905728483;5653.830385088433;5434.2935418395755;5575.832725018256;5589.105743081088;5621.213030175779;5626.409227031144;4968.403791545002;4991.376077990251;5004.124278251292;5019.4227663818765;5021.799128034874;22349.888250558804;22767.31698049061;22969.425764356023;23402;23443.765562344328;2417.9758202417925;2483.875806209682;2546.236129161263;2556.884940177678;2749.092799376201;14474.348654310475;14790.63023424423;14804.111753294728;14978.10131392109;15108;1929.8938558379284;2028.9391318260475;2058.5677007828226;2083.9374818755464;2086.0821238655094;59166;84709.15290847074;84752;84869.99999999939;85077;9951.751206219902;10151.441670708182;10219.080282774587;10435.791284174275;10490.423026733602;12338.753224935452;12720.681982950315;14334.354954027094;14339.211343376006;14415.207163606103;7748.147703752583;7906.367490600738;8223.424360294723;8271.13153118926;8449.493030418073;8160.918390816135;8203.876941845878;8234.505929644241;8378.204070613281;8417.873731894026;6361;6410.294867564564;6438.163038804925;6548.98490733937;6703.22912865017;1759.9912000440172;1795.6318954614273;1798.9100544972698;1817.8182181781715;1863.413024897153;999.0558921818812;1005.4118340770585;1038.7247379444452;1046.2362475393006;1063.4470075560664;18611.25554977813;18926.43220703381;19260.422187334403;20135;20187.192512299345;1015.2334987084852;1016.2987538598492;1052.847337136111;1056.6700425561771;1062.3572738493256;10797.575204833624;11368.147388945697;11439.027682646902;11496.482658280398;11510.597129100608;489856;491844;496869;501265;506420;99499.50250248847;102502;106418.46790765997;107092;108558.45720771345;1034.3225187502048;1049.7165765243342;1080.508368692243;1088.8257878739364;1089.7602527444108;17789.199486022902;17907.37324193647;18174.72737908906;18343.35798247055;18363;41824;42771.7861410691;43008.56991430045;43161.35257971196;43477.78261108674;61447.69276153591;62317;62449.68775156095;64264.35735642771;65294;8077.676892924217;8117.918820811717;8181.427300089174;8256.299481674134;8376.078631350425;8960.910390896008;12078;12730.872691272969;12958.740825183255;13065.869341306845;11104.389258590854;11129.499172537093;11188.720281993015;11249.49377278008;11369.204155709022;10800.08199303068;10996.670099897015;11168.218224724516;11400.543978240778;11413.828792568283;11204.49579768896;11474.94262528682;11700.35648039366;11747.941260293645;11770.234934729215;8081.111077781557;8102.270795628422;8296.875546866922;8325.875111873205;8504.23461888433;10118.898811011795;11243.212975091665;11349.4892729829;11403.258788178742;11498.77002459963 23:18:57 INFO - PROCESS | 21281 | |3;mootools.html;1538.192448964294;1544.3050627217751;1716.1590820497959;1814.628001259742;1815.7548730921321;7759.107702614201;8578.828423431536;8628.91371086289;9118.498482583454;9188.862167067491;8689.26141277992;8920.330975176872;8922.464652120878;8945.776355591113;8975.281977441806;2325.6860323856317;2361.347057060056;2368.0764501844296;2369.95260094798;2377.892994815229;2310.1452462588863;2356.740758516562;2357.929262122139;2387.164492427645;2389.95220095598;2362.2204672458047;2370.525894821034;2377.667126602277;2380.8928598213033;2383.6305372667266;2287.611106111957;2310.7920287174234;2314.6296592545195;2315.270689732737;2350.764923507653;2298.712660917385;2325.6976593042796;2338.481184404803;2339.356676913845;2345.108858633708;8802.295816334678;8949.552522373855;8955.05971872957;8978.23684986777;8987.415817971876;5266.210068489719;5351.197320401932;5398.595105367113;5400.270963419927;5404.5676345892625;3432.9313413731584;3479.530263414432;3536.381133301662;3579.2483578448555;3599.4600809878466;1033.1270076785179;1090.8145615245348;1123.1071298317856;1133.5975728616304;1148.6841118692344;12171.14801963863;12196.939015304955;12261.264324140491;12274.447649855689;12298.077644176723;4471.32930060487;4493.640508759291;4616.053708989716;4618.699784513996;4624.421947256593;9728.708138755848;9763.414195148314;9786.804263914682;9794.804103917882;9797.265205109645;5198.298229739013;5243.973780131151;5270.578353731769;5274.3670759509105;5274.657147285414;4300.225959327351;4875.561199492063;4903.975480122576;5065.569426598707;5082.822101226439;3801.125741079567;3801.334766415866;3821.8280177392085;3881.320768865494;3981.980090099531;1106.590561492253;1109.7614012987085;1115.8382034604938;1139.5555733264034;1140.2531341971023;894.1282249806517;924.916232131182;926.4348747264298;930.4091901642371;939.736873675372;5570.749316280777;5666.320041595035;5723.112917497794;5745.109508026263;5922.2597175353085;922.0318665401302;959.098447459386;979.911807937289;985.1330828870548;998.5656239535825;3630.49173115764;3910.902227444337;3915.941260881089;4028.8187031583648;4063.817128229237;10767.407792571326;11423.600173993871;11450.599229026944;11487.770244595062;11496.885031149748;10523.84214236787;10945.179111566664;11030.944845275882;11214.66356009321;11302.830457543143;8128.59357032146;8185.140560241089;8215.876761848576;8219.671213151407;8254;5656.660600363992;5766.711664416678;5856.267966504187;5910.320313164041;6010.1585777991995;9985.900140998787;10249.231307651808;10338.586456541954;10367.84448233262;10491;10342.896571034193;10535.57857685684;10593.311434756715;10667.253292269466;10717.196210284415;7162.89255661176;7875.330596899328;7929.691600885789;7932.4447288691545;8055.677772889018;8519.48883067018;8546.743597692079;8548;8609.440386374865;8694.217520423192;7639.121501027453;7878.763637090897;7881.290683838482;7930;7943.4836735612;7639.04511936008;7701.730439434593;7769.106552746506;7787.104482984303;7889.763307100796;7105.111861017373;7159.713611455483;7162.104736907887;7184.6048467334795;7240.637968101679;4841.564259216688;4951.480094590016;5018.247262910447;5084.618653600921;5132.230165475208;4993.076280888047;5014.548690617862;5028.773705183348;5040.495950405077;5085.949140508547 23:18:57 INFO - PROCESS | 21281 | |4;prototype.html;313.3513626792537;325.3022267236776;325.76870422000377;327.26692209450835;328.93425302021456;5496.367917689467;5569.6658200508;5679.318481782181;5686.914696279554;5714.599978001536;5556.638818476797;5570.777168913249;5588.86027849304;5594.216809646653;5612.382637909837;4328.545502722208;4348.717333373336;4360.498542667587;4369.077605853415;4369.169857727037;4086.1419101988618;4339;4355.473825181403;4359.149965756678;4391.187630288391;4264.78676066197;4295.914081718363;4307.267764480046;4308.116836048603;4317.892052698676;4309.008927946574;4334.5882141196535;4348.630366418843;4349.956500435019;4351.977285337943;3675.320065787838;3733.8879833605033;3771.264603402338;3782.432635104729;3795.1214148996146;5428.83713488596;5457.754401051962;5458.399576046631;5475.206095116226;5483.506484416422;4907.975460122677;4931.778069986859;4970.478099799505;4989.251612258154;4991.101601711691;5528.253685752412;5861.794837180678;5865.912011319833;5902.881942361171;5961.552883533709;4064.1465292288653;4084.0606660468256;4089.7750623715683;4090.427340172378;4125.690573206992;6115.379424452479;6129.295131059941;6155.322914479404;6177.474914632262;6211.285702144266;4102;4127.669786417079;4152.211079894811;4167.916641667211;4173.353130264813;4209.452771139732;4219.514755803122;4222.936655950163;4240.300350442204;4256.4679415073115;4887.413510378778;4913.238448040559;4914.803407863646;4918.926216106761;4957.206846904478;5769.653820770767;5835.883282334329;5850.619709718854;5912;5993.940060599425;1445.4868521674864;1448.6740483391152;1470.3897882378908;1470.955871323862;1474.9410023598937;906.1934877958622;923.4643906534194;935.9017303183203;945.6122989574242;945.7635591102224;5094.61790365724;5098.796048158032;5115.462876397999;5143.819966301162;5153.690778553224;5322.973385133049;5337.331334332834;5349.384820745664;5376.301080859464;5417.864553386197;5053.9241911371355;5084;5093.388793344822;5238.816641417532;5703.7148142592705;3207.550942868;3293.4236508610898;3296.538484612156;3310.8675652974093;3312.271300313927;6109.327973922865;6311.905321420182;6333.429991300713;6337.302896681362;6403.839904002437;6269.457756367638;6273.309935907047;6284.151639528696;6286.717097730613;6295.527835412362;6263.592866463665;6269.561130720897;6308.3060863305;6357.300696923335;6392.584482008655;5881.176635271151;6025.66858822769;6073.11733362659;6111.694415279308;6119.602225855305;5999.5500337473995;6011.849703757441;6067.24159480065;6067.423594758491;6080.179175811209;6018.21763170785;6255.37446255389;6293.496520278458;6363.87272254543;6402.839929001812;4220.430241917302;4258.978705106455;4269.146170765797;4277.310462367285;4299.656027517729;6133.969330153321;6246.9062964054665;6268;6277.027060805583;6277.18396608438;5778.595498315246;5953.464188223081;5965.9105113422465;6038.63768173911;6073.635581865102;5934.43622855828;5937.970310148422;5967.1944287522365;5973.970130149321;5976.462118409364;5762.337331206965;5791.189233507396;5822.272215973004;5839.941600583939;5876.882462350814;5944.108383742473;5963.970180149072;5991.670458124845;6014.9097763534455;6016.052134454174;5805.3614102447855;5806.506446952057;5847.421196277067;5871.588988770745;5891.23413956183 23:18:57 INFO - PROCESS | 21281 | |5;yui.html;785.1520358013346;806.3871457692152;818.5334359415136;828.8549503836826;830.1357182316318;14420.567382978514;15435.845641543596;15508.844911550881;15629.296681649324;15645.452409165682;14379.784303235434;14403.351849166786;14417.134971901693;14433.494827681006;14460.132392056485;661.8345413646589;770.218228498075;782.6869252299069;796.5977181523323;797.6849144587884;790.5533373643901;791.588374045496;795.8249185179251;796.3191471292049;796.4464697035562;841.7979684875647;844.7254642241296;845.0619812008665;846.1792061700141;847.9576021198947;833.7998880268752;842.751388340441;842.9157084291522;843.2663582683043;844.4637655089032;611.0406661541382;611.1627070912833;611.1749138662814;611.8256296955346;612.494691879197;14625.92687036558;14679.63300917475;14681.853181468156;14683.706325873423;14710.411583536752;14526;14996.700065998728;15183;15237.16195609241;15372.385104595915;12023.579174728842;12184.329861857594;12230.205036672587;12316.076294277966;12866.035047371392;3692.2615476904457;3791.279656865187;3791.374423220165;3814.256220037094;4441.15618032576;60608.69695651538;61046.38953610496;61625.38374616241;62273.37726622766;62976.68511657458;11083.50124244411;11103.777924441465;11186.664400068012;11195.104391648649;11334.60328888485;19414.611707765765;19477.513062173275;19515.414537563895;19545;19563.217471301272;8549.871751923725;8657.870131948026;8688.391812573185;8746.518941458153;8825.338099642553;4711.128441238382;4720.527947205252;4756.762161891892;4871.902561948741;4908.754562271872;3317.3365326934227;3444.190615205425;3468.722502199818;3469.2714529948994;3475.287566048954;642.2549842183139;644.1658052821607;649.0588646462649;658.2857599504591;673.592476551689;744.162816831065;751.2412463411994;773.4469854054378;773.6943907156755;778.5990215039259;9655.662051828152;9865.950670246602;9914.206863450907;9960.950195248977;10021.549030293654;718.1633397092434;723.8588475247382;725.856775578466;736.1975446762947;736.6316841579211;4477.977610111929;4693.601043911238;4696.600788932979;4721.1501929652995;4753.31076993834;69309;71637.64181179061;71937;74651.62674186703;74710;31488.370232595676;32026.83986580052;56435.71782141063;59817.40182598205;68129.65935170293;60256.39743602595;60733.69633151806;60943;61971.69014154901;62136.06795898065;9489.383190092622;9728.173105285847;9876.802463950537;9907.801843963225;9999.800004000024;39098;39167.60832391639;39223.2155356897;39829.40255896106;40075.599244007186;51285.48714512807;54397.45602543924;54479;54863;55694.72152639211;12503.812442813547;12666.303353315656;12911.806322904977;13231.603051908458;13247.20516768997;13682.31588420595;13906.167842296878;14191;14243.21662308583;14552.563423097325;13141.605751827461;13315.334233288491;13429.127106738031;13649.38577763983;14065.296735163405;12200.938995304967;12204;12375.75248495007;12401.353993984283;12716.491340346283;12649.367531623566;13067.673308166992;13127.34363281813;13216.933915330746;13241.602751917459;10621.309614875008;10782.406967616856;10822.296550724177;11132;11327.886721133013;11163.832542512031;11301.281670331464;11304.773904522028;11460.19778615489;11475.713107172387 23:18:57 INFO - PROCESS | 21281 | __end_tp_report 23:18:57 INFO - PROCESS | 21281 | __start_cc_report 23:18:57 INFO - PROCESS | 21281 | _x_x_mozilla_cycle_collect,354 23:18:57 INFO - PROCESS | 21281 | __end_cc_report 23:18:57 INFO - PROCESS | 21281 | __startTimestamp1463725137597__endTimestamp 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | ------- Summary: start ------- 23:18:57 INFO - PROCESS | 21281 | Number of tests: 6 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | [#0] dojo.html Cycles:165 Average:5359.18 Median:5632.49 stddev:614.84 (10.9%) stddev-sans-first:591.15 23:18:57 INFO - PROCESS | 21281 | Values: 3122.3 3173.3 3188.9 3195.2 3202.9 5300.8 5407.0 5423.1 5460.6 5466.1 5254.4 5289.9 5369.7 5386.5 5426.8 4820.5 4860.2 4903.4 4993.1 4997.9 4830.3 4934.8 4995.6 4996.7 5026.5 4755.3 4772.9 4836.8 4897.8 4938.1 4809.5 4830.9 4833.1 4849.5 4898.0 4776.3 4782.4 4783.8 4797.9 4805.6 5122.2 5213.0 5254.1 5289.5 5335.7 5680.8 5698.3 5703.5 5712.3 5752.5 5726.1 5819.6 5838.9 5852.2 5855.0 5361.6 5387.6 5396.2 5399.1 5416.4 5836.3 5866.0 5873.0 5887.7 5906.0 5574.4 5577.2 5664.7 5681.3 5689.5 5688.3 5721.4 5764.6 5765.0 5821.2 5677.3 5684.4 5755.0 5767.4 5770.7 5700.2 5763.4 5766.4 5767.3 5831.5 4328.3 4381.4 4398.5 4399.9 4435.2 3881.7 3885.2 3935.0 3935.5 3942.7 5468.6 5525.1 5529.3 5531.3 5589.7 5770.7 5812.6 5814.1 5816.1 5818.4 5397.3 5466.3 5501.8 5511.9 5519.4 4948.1 5022.4 5032.1 5036.3 5075.7 5974.1 6009.3 6034.4 6040.0 6046.3 5685.3 5789.1 5811.2 5859.9 5867.4 5968.7 6011.6 6019.9 6022.7 6054.5 5690.5 5715.3 5750.8 5753.4 5773.2 5684.5 5756.4 5812.7 5813.3 5843.0 5684.6 5700.2 5704.0 5715.0 5729.3 5694.7 5696.7 5698.1 5704.3 5707.3 5355.1 5373.7 5403.6 5409.9 5446.6 5632.5 5661.8 5686.2 5723.9 5747.1 5647.0 5651.2 5658.0 5659.8 5666.2 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | [#1] ext.html Cycles:180 Average:63704.83 Median:43434.67 stddev:126380.31 (291.0%) stddev-sans-first:126645.99 23:18:57 INFO - PROCESS | 21281 | Values: 588.8 611.0 614.8 633.2 654.4 43769.3 44387.8 44783.6 48125.3 48297.5 46547.5 47300.8 47318.5 47357.8 47357.9 8873.2 8934.8 9007.0 9010.9 9060.9 8895.3 8967.8 8976.5 8991.0 8998.0 8968.5 9020.6 9067.1 9069.3 9071.1 8947.3 8981.3 8995.7 9018.6 9050.5 8841.8 8910.3 8963.0 8964.1 8974.7 43045.0 43374.8 43377.0 43606.0 43873.6 3374.4 3416.5 3538.9 3768.5 3872.5 32472.7 32747.0 33263.7 33282.3 33317.0 4321.3 4357.2 4379.5 4388.7 4534.8 345628.0 346622.0 347574.0 349365.0 349643.0 58856.0 58929.0 59398.4 59408.0 59764.7 65064.7 65254.7 65589.0 65951.0 66034.0 16987.5 17190.4 17198.4 17267.2 17274.0 13046.6 13077.1 13164.9 13183.7 13222.2 17361.0 18612.6 18910.3 19146.2 19179.7 3195.9 3298.3 3327.1 5994.6 6008.8 1032.6 1042.5 1113.1 1153.4 1231.1 33004.3 34183.1 34318.7 36083.0 36510.5 1115.7 1153.5 1158.8 1158.8 1228.0 17358.4 18216.1 19025.0 19383.5 19448.3 552167.0 553114.0 554304.0 554789.0 557937.0 61695.0 61814.0 62239.7 62337.0 62745.4 1367.4 1413.7 1425.3 1436.6 1460.0 32760.3 34578.3 34848.1 34894.0 35096.5 54622.0 54957.0 55418.2 55506.2 55558.4 494894.0 502109.0 503948.0 504235.0 504538.0 29062.7 29349.0 29643.9 29794.6 29839.6 49588.0 50162.5 50984.2 51125.0 51180.0 43492.3 44002.0 44150.3 44408.0 44999.6 42988.8 43016.1 43054.1 43126.0 43617.0 42436.2 43125.6 43212.6 43265.8 43299.6 36747.4 38860.0 39272.2 39679.4 40073.6 29126.3 29326.6 29412.6 29524.9 29849.3 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | [#2] jquery.html Cycles:180 Average:29680.05 Median:12217.99 stddev:82310.00 (673.7%) stddev-sans-first:82511.50 23:18:57 INFO - PROCESS | 21281 | Values: 378.3 379.1 383.3 397.6 398.1 15349.7 17315.6 18568.4 20050.5 20050.8 17767.6 18130.1 18173.6 18458.3 18497.2 5093.3 5540.4 5724.6 5739.7 5758.0 5603.6 5607.0 5608.9 5644.3 5647.9 5428.2 5633.6 5641.9 5645.5 5653.8 5434.3 5575.8 5589.1 5621.2 5626.4 4968.4 4991.4 5004.1 5019.4 5021.8 22349.9 22767.3 22969.4 23402.0 23443.8 2418.0 2483.9 2546.2 2556.9 2749.1 14474.3 14790.6 14804.1 14978.1 15108.0 1929.9 2028.9 2058.6 2083.9 2086.1 59166.0 84709.2 84752.0 84870.0 85077.0 9951.8 10151.4 10219.1 10435.8 10490.4 12338.8 12720.7 14334.4 14339.2 14415.2 7748.1 7906.4 8223.4 8271.1 8449.5 8160.9 8203.9 8234.5 8378.2 8417.9 6361.0 6410.3 6438.2 6549.0 6703.2 1760.0 1795.6 1798.9 1817.8 1863.4 999.1 1005.4 1038.7 1046.2 1063.4 18611.3 18926.4 19260.4 20135.0 20187.2 1015.2 1016.3 1052.8 1056.7 1062.4 10797.6 11368.1 11439.0 11496.5 11510.6 489856.0 491844.0 496869.0 501265.0 506420.0 99499.5 102502.0 106418.5 107092.0 108558.5 1034.3 1049.7 1080.5 1088.8 1089.8 17789.2 17907.4 18174.7 18343.4 18363.0 41824.0 42771.8 43008.6 43161.4 43477.8 61447.7 62317.0 62449.7 64264.4 65294.0 8077.7 8117.9 8181.4 8256.3 8376.1 8960.9 12078.0 12730.9 12958.7 13065.9 11104.4 11129.5 11188.7 11249.5 11369.2 10800.1 10996.7 11168.2 11400.5 11413.8 11204.5 11474.9 11700.4 11747.9 11770.2 8081.1 8102.3 8296.9 8325.9 8504.2 10118.9 11243.2 11349.5 11403.3 11498.8 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | [#3] mootools.html Cycles:180 Average:5819.72 Median:5016.40 stddev:3332.97 (66.4%) stddev-sans-first:3326.79 23:18:57 INFO - PROCESS | 21281 | Values: 1538.2 1544.3 1716.2 1814.6 1815.8 7759.1 8578.8 8628.9 9118.5 9188.9 8689.3 8920.3 8922.5 8945.8 8975.3 2325.7 2361.3 2368.1 2370.0 2377.9 2310.1 2356.7 2357.9 2387.2 2390.0 2362.2 2370.5 2377.7 2380.9 2383.6 2287.6 2310.8 2314.6 2315.3 2350.8 2298.7 2325.7 2338.5 2339.4 2345.1 8802.3 8949.6 8955.1 8978.2 8987.4 5266.2 5351.2 5398.6 5400.3 5404.6 3432.9 3479.5 3536.4 3579.2 3599.5 1033.1 1090.8 1123.1 1133.6 1148.7 12171.1 12196.9 12261.3 12274.4 12298.1 4471.3 4493.6 4616.1 4618.7 4624.4 9728.7 9763.4 9786.8 9794.8 9797.3 5198.3 5244.0 5270.6 5274.4 5274.7 4300.2 4875.6 4904.0 5065.6 5082.8 3801.1 3801.3 3821.8 3881.3 3982.0 1106.6 1109.8 1115.8 1139.6 1140.3 894.1 924.9 926.4 930.4 939.7 5570.7 5666.3 5723.1 5745.1 5922.3 922.0 959.1 979.9 985.1 998.6 3630.5 3910.9 3915.9 4028.8 4063.8 10767.4 11423.6 11450.6 11487.8 11496.9 10523.8 10945.2 11030.9 11214.7 11302.8 8128.6 8185.1 8215.9 8219.7 8254.0 5656.7 5766.7 5856.3 5910.3 6010.2 9985.9 10249.2 10338.6 10367.8 10491.0 10342.9 10535.6 10593.3 10667.3 10717.2 7162.9 7875.3 7929.7 7932.4 8055.7 8519.5 8546.7 8548.0 8609.4 8694.2 7639.1 7878.8 7881.3 7930.0 7943.5 7639.0 7701.7 7769.1 7787.1 7889.8 7105.1 7159.7 7162.1 7184.6 7240.6 4841.6 4951.5 5018.2 5084.6 5132.2 4993.1 5014.5 5028.8 5040.5 5085.9 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | [#4] prototype.html Cycles:180 Average:4915.78 Median:5542.45 stddev:1479.89 (26.7%) stddev-sans-first:1443.16 23:18:57 INFO - PROCESS | 21281 | Values: 313.4 325.3 325.8 327.3 328.9 5496.4 5569.7 5679.3 5686.9 5714.6 5556.6 5570.8 5588.9 5594.2 5612.4 4328.5 4348.7 4360.5 4369.1 4369.2 4086.1 4339.0 4355.5 4359.1 4391.2 4264.8 4295.9 4307.3 4308.1 4317.9 4309.0 4334.6 4348.6 4350.0 4352.0 3675.3 3733.9 3771.3 3782.4 3795.1 5428.8 5457.8 5458.4 5475.2 5483.5 4908.0 4931.8 4970.5 4989.3 4991.1 5528.3 5861.8 5865.9 5902.9 5961.6 4064.1 4084.1 4089.8 4090.4 4125.7 6115.4 6129.3 6155.3 6177.5 6211.3 4102.0 4127.7 4152.2 4167.9 4173.4 4209.5 4219.5 4222.9 4240.3 4256.5 4887.4 4913.2 4914.8 4918.9 4957.2 5769.7 5835.9 5850.6 5912.0 5993.9 1445.5 1448.7 1470.4 1471.0 1474.9 906.2 923.5 935.9 945.6 945.8 5094.6 5098.8 5115.5 5143.8 5153.7 5323.0 5337.3 5349.4 5376.3 5417.9 5053.9 5084.0 5093.4 5238.8 5703.7 3207.6 3293.4 3296.5 3310.9 3312.3 6109.3 6311.9 6333.4 6337.3 6403.8 6269.5 6273.3 6284.2 6286.7 6295.5 6263.6 6269.6 6308.3 6357.3 6392.6 5881.2 6025.7 6073.1 6111.7 6119.6 5999.6 6011.8 6067.2 6067.4 6080.2 6018.2 6255.4 6293.5 6363.9 6402.8 4220.4 4259.0 4269.1 4277.3 4299.7 6134.0 6246.9 6268.0 6277.0 6277.2 5778.6 5953.5 5965.9 6038.6 6073.6 5934.4 5938.0 5967.2 5974.0 5976.5 5762.3 5791.2 5822.3 5839.9 5876.9 5944.1 5964.0 5991.7 6014.9 6016.1 5805.4 5806.5 5847.4 5871.6 5891.2 23:18:57 INFO - PROCESS | 21281 | 23:18:57 INFO - PROCESS | 21281 | [#5] yui.html Cycles:180 Average:16147.79 Median:4703.86 stddev:19497.05 (414.5%) stddev-sans-first:19517.61 23:18:57 INFO - PROCESS | 21281 | Values: 785.2 806.4 818.5 828.9 830.1 14420.6 15435.8 15508.8 15629.3 15645.5 14379.8 14403.4 14417.1 14433.5 14460.1 661.8 770.2 782.7 796.6 797.7 790.6 791.6 795.8 796.3 796.4 841.8 844.7 845.1 846.2 848.0 833.8 842.8 842.9 843.3 844.5 611.0 611.2 611.2 611.8 612.5 14625.9 14679.6 14681.9 14683.7 14710.4 14526.0 14996.7 15183.0 15237.2 15372.4 12023.6 12184.3 12230.2 12316.1 12866.0 3692.3 3791.3 3791.4 3814.3 4441.2 60608.7 61046.4 61625.4 62273.4 62976.7 11083.5 11103.8 11186.7 11195.1 11334.6 19414.6 19477.5 19515.4 19545.0 19563.2 8549.9 8657.9 8688.4 8746.5 8825.3 4711.1 4720.5 4756.8 4871.9 4908.8 3317.3 3444.2 3468.7 3469.3 3475.3 642.3 644.2 649.1 658.3 673.6 744.2 751.2 773.4 773.7 778.6 9655.7 9866.0 9914.2 9961.0 10021.5 718.2 723.9 725.9 736.2 736.6 4478.0 4693.6 4696.6 4721.2 4753.3 69309.0 71637.6 71937.0 74651.6 74710.0 31488.4 32026.8 56435.7 59817.4 68129.7 60256.4 60733.7 60943.0 61971.7 62136.1 9489.4 9728.2 9876.8 9907.8 9999.8 39098.0 39167.6 39223.2 39829.4 40075.6 51285.5 54397.5 54479.0 54863.0 55694.7 12503.8 12666.3 12911.8 13231.6 13247.2 13682.3 13906.2 14191.0 14243.2 14552.6 13141.6 13315.3 13429.1 13649.4 14065.3 12200.9 12204.0 12375.8 12401.4 12716.5 12649.4 13067.7 13127.3 13216.9 13241.6 10621.3 10782.4 10822.3 11132.0 11327.9 11163.8 11301.3 11304.8 11460.2 11475.7 23:18:57 INFO - PROCESS | 21281 | -------- Summary: end -------- 23:18:57 INFO - PROCESS | 21281 | 23:18:58 INFO - TEST-INFO | 21281: exit 0 23:18:58 INFO - TEST-OK | dromaeo_css | took 1110053ms 23:18:58 INFO - TEST-START | kraken 23:18:58 INFO - operating with platform_type : linux_ 23:18:58 INFO - Initialising browser for kraken test... 23:18:58 INFO - TEST-INFO | started process 21522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Nn5mi/profile http://localhost:49927/getInfo.html) 23:19:01 INFO - PROCESS | 21522 | 23:19:01 INFO - PROCESS | 21522 | (process:21588): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:19:02 INFO - PROCESS | 21522 | __metrics Screen width/height:1600/1200 23:19:02 INFO - PROCESS | 21522 | colorDepth:24 23:19:02 INFO - PROCESS | 21522 | Browser inner width/height: 1024/697 23:19:02 INFO - PROCESS | 21522 | __metrics 23:19:03 ERROR - PROCESS | 21522 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:19:04 INFO - TEST-INFO | 21522: exit 0 23:19:04 INFO - Browser initialized. 23:19:04 INFO - Running cycle 1/1 for kraken test... 23:19:04 INFO - TEST-INFO | started process 21639 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7Nn5mi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:06 INFO - PROCESS | 21639 | 23:19:06 INFO - PROCESS | 21639 | (process:21702): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:19:16 INFO - PROCESS | 21639 | RSS: Main: 164388864 23:19:16 INFO - PROCESS | 21639 | 23:19:18 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49927/tests/kraken/driver.html?testName=audio-beat-detection) 23:19:19 INFO - PROCESS | 21639 | RSS: Main: 169287680 23:19:19 INFO - PROCESS | 21639 | 23:19:22 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49927/tests/kraken/driver.html?testName=audio-dft) 23:19:22 INFO - PROCESS | 21639 | RSS: Main: 169308160 23:19:22 INFO - PROCESS | 21639 | 23:19:25 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49927/tests/kraken/driver.html?testName=audio-fft) 23:19:26 INFO - PROCESS | 21639 | RSS: Main: 163942400 23:19:26 INFO - PROCESS | 21639 | 23:19:28 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49927/tests/kraken/driver.html?testName=audio-oscillator) 23:19:29 INFO - PROCESS | 21639 | RSS: Main: 164720640 23:19:29 INFO - PROCESS | 21639 | 23:19:31 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49927/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:19:32 INFO - PROCESS | 21639 | RSS: Main: 164655104 23:19:32 INFO - PROCESS | 21639 | 23:19:35 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49927/tests/kraken/driver.html?testName=imaging-darkroom) 23:19:35 INFO - PROCESS | 21639 | RSS: Main: 164843520 23:19:35 INFO - PROCESS | 21639 | 23:19:39 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49927/tests/kraken/driver.html?testName=imaging-desaturate) 23:19:40 INFO - PROCESS | 21639 | RSS: Main: 163401728 23:19:40 INFO - PROCESS | 21639 | 23:19:43 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49927/tests/kraken/driver.html?testName=json-parse-financial) 23:19:43 INFO - PROCESS | 21639 | RSS: Main: 164237312 23:19:43 INFO - PROCESS | 21639 | 23:19:45 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49927/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:19:45 INFO - PROCESS | 21639 | RSS: Main: 164466688 23:19:45 INFO - PROCESS | 21639 | 23:19:47 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:19:48 INFO - PROCESS | 21639 | RSS: Main: 164352000 23:19:48 INFO - PROCESS | 21639 | 23:19:50 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:19:51 INFO - PROCESS | 21639 | RSS: Main: 164491264 23:19:51 INFO - PROCESS | 21639 | 23:19:53 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:19:54 INFO - PROCESS | 21639 | RSS: Main: 164864000 23:19:54 INFO - PROCESS | 21639 | 23:19:57 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:19:57 INFO - PROCESS | 21639 | RSS: Main: 164896768 23:19:57 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | Cycle 1(1): loaded http://localhost:49927/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49927/tests/kraken/driver.html?testName=ai-astar) 23:19:59 INFO - PROCESS | 21639 | RSS: Main: 166211584 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | __start_tp_report 23:19:59 INFO - PROCESS | 21639 | _x_x_mozilla_page_load 23:19:59 INFO - PROCESS | 21639 | _x_x_mozilla_page_load_details 23:19:59 INFO - PROCESS | 21639 | |i|pagename|runs| 23:19:59 INFO - PROCESS | 21639 | |0;ai-astar;107;111;107;109;111;111;105;109;110;111 23:19:59 INFO - PROCESS | 21639 | |1;audio-beat-detection;155;125;161;216;127;154;152;134;182;125 23:19:59 INFO - PROCESS | 21639 | |2;audio-dft;164;163;164;163;167;175;163;164;162;164 23:19:59 INFO - PROCESS | 21639 | |3;audio-fft;75;76;76;158;75;76;75;75;172;76 23:19:59 INFO - PROCESS | 21639 | |4;audio-oscillator;92;92;191;93;92;94;148;91;93;162 23:19:59 INFO - PROCESS | 21639 | |5;imaging-gaussian-blur;119;117;117;118;119;119;118;117;118;118 23:19:59 INFO - PROCESS | 21639 | |6;imaging-darkroom;147;147;147;146;146;147;146;147;147;147 23:19:59 INFO - PROCESS | 21639 | |7;imaging-desaturate;96;95;95;97;96;96;96;96;96;95 23:19:59 INFO - PROCESS | 21639 | |8;json-parse-financial;55;56;57;57;55;55;58;110;53;53 23:19:59 INFO - PROCESS | 21639 | |9;json-stringify-tinderbox;62;62;63;62;62;63;62;62;62;61 23:19:59 INFO - PROCESS | 21639 | |10;stanford-crypto-aes;81;81;78;79;79;79;79;78;80;81 23:19:59 INFO - PROCESS | 21639 | |11;stanford-crypto-ccm;122;121;138;120;119;128;120;130;210;125 23:19:59 INFO - PROCESS | 21639 | |12;stanford-crypto-pbkdf2;160;160;165;159;156;162;157;161;160;158 23:19:59 INFO - PROCESS | 21639 | |13;stanford-crypto-sha256-iterative;65;64;62;65;65;62;62;62;61;62 23:19:59 INFO - PROCESS | 21639 | __end_tp_report 23:19:59 INFO - PROCESS | 21639 | __start_cc_report 23:19:59 INFO - PROCESS | 21639 | _x_x_mozilla_cycle_collect,837 23:19:59 INFO - PROCESS | 21639 | __end_cc_report 23:19:59 INFO - PROCESS | 21639 | __startTimestamp1463725199878__endTimestamp 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | ------- Summary: start ------- 23:19:59 INFO - PROCESS | 21639 | Number of tests: 14 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.50 stddev:2.13 (1.9%) stddev-sans-first:2.12 23:19:59 INFO - PROCESS | 21639 | Values: 107.0 111.0 107.0 109.0 111.0 111.0 105.0 109.0 110.0 111.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#1] audio-beat-detection Cycles:10 Average:153.10 Median:154.50 stddev:28.84 (18.7%) stddev-sans-first:30.58 23:19:59 INFO - PROCESS | 21639 | Values: 155.0 125.0 161.0 216.0 127.0 154.0 152.0 134.0 182.0 125.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.00 stddev:3.78 (2.3%) stddev-sans-first:4.00 23:19:59 INFO - PROCESS | 21639 | Values: 164.0 163.0 164.0 163.0 167.0 175.0 163.0 164.0 162.0 164.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#3] audio-fft Cycles:10 Average:93.40 Median:75.50 stddev:37.88 (50.2%) stddev-sans-first:39.59 23:19:59 INFO - PROCESS | 21639 | Values: 75.0 76.0 76.0 158.0 75.0 76.0 75.0 75.0 172.0 76.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#4] audio-oscillator Cycles:10 Average:114.80 Median:92.00 stddev:37.48 (40.7%) stddev-sans-first:38.84 23:19:59 INFO - PROCESS | 21639 | Values: 92.0 92.0 191.0 93.0 92.0 94.0 148.0 91.0 93.0 162.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 23:19:59 INFO - PROCESS | 21639 | Values: 119.0 117.0 117.0 118.0 119.0 119.0 118.0 117.0 118.0 118.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 23:19:59 INFO - PROCESS | 21639 | Values: 147.0 147.0 147.0 146.0 146.0 147.0 146.0 147.0 147.0 147.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 23:19:59 INFO - PROCESS | 21639 | Values: 96.0 95.0 95.0 97.0 96.0 96.0 96.0 96.0 96.0 95.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:55.50 stddev:17.33 (31.2%) stddev-sans-first:18.25 23:19:59 INFO - PROCESS | 21639 | Values: 55.0 56.0 57.0 57.0 55.0 55.0 58.0 110.0 53.0 53.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 23:19:59 INFO - PROCESS | 21639 | Values: 62.0 62.0 63.0 62.0 62.0 63.0 62.0 62.0 62.0 61.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.50 stddev:1.18 (1.5%) stddev-sans-first:1.12 23:19:59 INFO - PROCESS | 21639 | Values: 81.0 81.0 78.0 79.0 79.0 79.0 79.0 78.0 80.0 81.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:126.50 stddev:27.59 (21.8%) stddev-sans-first:28.96 23:19:59 INFO - PROCESS | 21639 | Values: 122.0 121.0 138.0 120.0 119.0 128.0 120.0 130.0 210.0 125.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:160.00 stddev:2.57 (1.6%) stddev-sans-first:2.73 23:19:59 INFO - PROCESS | 21639 | Values: 160.0 160.0 165.0 159.0 156.0 162.0 157.0 161.0 160.0 158.0 23:19:59 INFO - PROCESS | 21639 | 23:19:59 INFO - PROCESS | 21639 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.56 (2.5%) stddev-sans-first:1.48 23:19:59 INFO - PROCESS | 21639 | Values: 65.0 64.0 62.0 65.0 65.0 62.0 62.0 62.0 61.0 62.0 23:19:59 INFO - PROCESS | 21639 | -------- Summary: end -------- 23:19:59 INFO - PROCESS | 21639 | 23:20:00 INFO - TEST-INFO | 21639: exit 0 23:20:00 INFO - TEST-OK | kraken | took 62541ms 23:20:00 INFO - SUITE-END | took 1172s 23:20:01 INFO - Completed test suite (00:19:32) 23:20:01 INFO - javascript benchmark 23:20:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7285.442561019135, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.266267427154, 3173.2701478659924, 3188.856501457434, 3195.217171792911, 3202.919927001823, 5300.761465734036, 5407.0, 5423.105187644034, 5460.645058071223, 5466.070767969445, 5254.395744489385, 5289.947100528993, 5369.704666243355, 5386.48828361306, 5426.75579598918, 4820.517948205178, 4860.173770459013, 4903.387076615423, 4993.101241776479, 4997.875053123665, 4830.3237546743485, 4934.802607895698, 4995.6003519718515, 4996.650234483587, 5026.472220416858, 4755.310479980418, 4772.904541909143, 4836.782344794493, 4897.804087836482, 4938.061768264036, 4809.470958194595, 4830.864746784503, 4833.130036593412, 4849.515048495157, 4898.044881248158, 4776.331313616097, 4782.426108866924, 4783.808647654089, 4797.904041919177, 4805.6155507559315, 5122.1804511278015, 5213.035588416117, 5254.054270231357, 5289.497497737709, 5335.653182543122, 5680.772769089232, 5698.34469036062, 5703.54371650267, 5712.343080545749, 5752.482276595127, 5726.055200891889, 5819.621724587888, 5838.9124163137585, 5852.180694702745, 5855.0, 5361.571074314045, 5387.568994480433, 5396.163594642837, 5399.136138217867, 5416.431274716177, 5836.270466191726, 5866.0, 5872.970635146797, 5887.735051922674, 5906.0, 5574.414686457944, 5577.2191893135, 5664.688442135722, 5681.318241811009, 5689.516391106719, 5688.288963879515, 5721.370649228582, 5764.625299355528, 5765.019946609086, 5821.243238378984, 5677.290338707661, 5684.37471878093, 5754.992876246723, 5767.394423585547, 5770.711464426846, 5700.173474846124, 5763.366029736726, 5766.394528574523, 5767.307923049261, 5831.475167234969, 4328.28583283761, 4381.364702118175, 4398.538153493901, 4399.934000989988, 4435.223835828717, 3881.7476864003747, 3885.2229554089295, 3935.035916200492, 3935.4687117239378, 3942.6845852331744, 5468.644538105011, 5525.0883604204855, 5529.253550770594, 5531.3362396512675, 5589.74846131925, 5770.682612456271, 5812.59311848175, 5814.069748840166, 5816.127580862904, 5818.389069147764, 5397.325334333208, 5466.289382380344, 5501.807436739695, 5511.889762204733, 5519.3652729936575, 4948.084604348207, 5022.447530771612, 5032.119379108642, 5036.345275114211, 5075.746212689424, 5974.133750606138, 6009.338972712998, 6034.426729460694, 6040.003399439043, 6046.304674962347, 5685.346185188756, 5789.131630255327, 5811.186433899173, 5859.9121013183985, 5867.413258674099, 5968.731407086605, 6011.579189456696, 6019.939800602113, 6022.698865056817, 6054.515638748801, 5690.487856092972, 5715.2855893013375, 5750.769969201185, 5753.424657534213, 5773.16289138081, 5684.516816070681, 5756.42435756421, 5812.709364531841, 5813.302403711582, 5843.035899076604, 5684.573656975661, 5700.173474846207, 5704.0, 5715.028445164249, 5729.255196824386, 5694.658320500617, 5696.743646535833, 5698.1452782083015, 5704.3154821421695, 5707.258056452634, 5355.089634762177, 5373.650712703662, 5403.621746477709, 5409.945900541102, 5446.618736688552, 5632.493075623213, 5661.801836935688, 5686.175504551899, 5723.885522289447, 5747.137929310469, 5647.0, 5651.208830763779, 5658.0, 5659.773609055592, 5666.2350582670815], "value": 5317.684177711111, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.8262962426084, 611.0132136599391, 614.8186285045911, 633.2401118657612, 654.365265692279, 43769.34345984809, 44387.77806110969, 44783.552164478344, 48125.27812082822, 48297.51702482974, 46547.534524654744, 47300.76349618247, 47318.52681473184, 47357.7632111839, 47357.86889589968, 8873.245774109208, 8934.821303573925, 9006.954965225164, 9010.86483702745, 9060.909390906105, 8895.288376929862, 8967.775805604879, 8976.461412315282, 8991.0, 8998.0, 8968.46189228645, 9020.639174433014, 9067.138621830949, 9069.31980101495, 9071.138241867013, 8947.284217262604, 8981.281497480186, 8995.74059631652, 9018.639254429814, 9050.547472626375, 8841.823163536694, 8910.287177025823, 8963.0, 8964.05877382872, 8974.730758077269, 43045.0, 43374.78312608448, 43377.0, 43606.037728301984, 43873.56126438727, 3374.409478341305, 3416.521686963802, 3538.929221415557, 3768.510093687803, 3872.5075829148395, 32472.6752732472, 32747.0, 33263.66736332654, 33282.33435331304, 33317.0, 4321.286987647034, 4357.150355680644, 4379.474463064421, 4388.736675799462, 4534.841280555198, 345628.0, 346622.0, 347574.0, 349365.0, 349642.99999999744, 58856.0, 58929.0, 59398.40601593929, 59408.0, 59764.701176493836, 65064.67467662632, 65254.67372663107, 65589.0, 65951.0, 66034.0, 16987.490375288762, 17190.398336058177, 17198.398056067977, 17267.222974966156, 17274.0, 13046.608601741962, 13077.084604077623, 13164.934175329254, 13183.736325273441, 13222.206667599974, 17360.974743082577, 18612.627747445247, 18910.338138165374, 19146.23415063382, 19179.712304315446, 3195.872165113416, 3298.274379636476, 3327.072176484678, 5994.610350327215, 6008.789692360747, 1032.6437379104198, 1042.5464922758624, 1113.142879982409, 1153.4358831211596, 1231.0889941443268, 33004.3399132016, 34183.145421364665, 34318.65681343205, 36083.0, 36510.45234321487, 1115.67087709126, 1153.4809335798925, 1158.7856246594406, 1158.8261760735788, 1228.011450782129, 17358.39245626397, 18216.077890401564, 19025.0, 19383.515412114528, 19448.319308824408, 552167.0, 553114.0, 554304.0, 554789.0, 557937.0, 61695.0, 61814.0, 62239.688801555705, 62337.0, 62745.37254627486, 1367.446184295362, 1413.688988422566, 1425.287356321839, 1436.5833908166514, 1459.9781003284959, 32760.344793103526, 34578.308433831684, 34848.128796780285, 34894.0, 35096.47355289724, 54622.0, 54957.0, 55418.16872746831, 55506.167407488116, 55558.44441555533, 494894.0, 502109.0, 503948.0, 504235.0, 504538.0, 29062.709372906, 29349.0, 29643.85178074096, 29794.553081703358, 29839.552406713483, 49588.0, 50162.544874352396, 50984.23523647074, 51125.0, 51180.0, 43492.34761478517, 44002.0, 44150.33774493321, 44408.0, 44999.550004499535, 42988.78505607577, 43016.139677206906, 43054.13891722211, 43126.0, 43617.0, 42436.15127697367, 43125.56874431216, 43212.567874320855, 43265.783671081444, 43299.567004329554, 36747.44878826873, 38860.0, 39272.21455570815, 39679.40480892847, 40073.59926400815, 29126.27184320409, 29326.56010159892, 29412.55881161827, 29524.852375737984, 29849.25376865509], "value": 20722.023907680832, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.2737144682211, 379.0920744816165, 383.342567496743, 397.58452417224, 398.14796335841305, 15349.69300613987, 17315.567110822234, 18568.35010774623, 20050.498737531587, 20050.799492005077, 17767.64464710705, 18130.093495325247, 18173.63652726948, 18458.261669533204, 18497.16762745676, 5093.286939828427, 5540.36285827131, 5724.570657200706, 5739.655620662774, 5758.049921762904, 5603.551715862742, 5606.971965140189, 5608.915866261989, 5644.322681278252, 5647.915281270784, 5428.240046393508, 5633.63381380211, 5641.943580564182, 5645.491905728483, 5653.830385088433, 5434.2935418395755, 5575.832725018256, 5589.105743081088, 5621.213030175779, 5626.409227031144, 4968.403791545002, 4991.376077990251, 5004.124278251292, 5019.4227663818765, 5021.799128034874, 22349.888250558804, 22767.31698049061, 22969.425764356023, 23402.0, 23443.765562344328, 2417.9758202417925, 2483.875806209682, 2546.236129161263, 2556.884940177678, 2749.092799376201, 14474.348654310475, 14790.63023424423, 14804.111753294728, 14978.10131392109, 15108.0, 1929.8938558379284, 2028.9391318260475, 2058.5677007828226, 2083.9374818755464, 2086.0821238655094, 59166.0, 84709.15290847074, 84752.0, 84869.99999999939, 85077.0, 9951.751206219902, 10151.441670708182, 10219.080282774587, 10435.791284174275, 10490.423026733602, 12338.753224935452, 12720.681982950315, 14334.354954027094, 14339.211343376006, 14415.207163606103, 7748.147703752583, 7906.367490600738, 8223.424360294723, 8271.13153118926, 8449.493030418073, 8160.918390816135, 8203.876941845878, 8234.505929644241, 8378.204070613281, 8417.873731894026, 6361.0, 6410.294867564564, 6438.163038804925, 6548.98490733937, 6703.22912865017, 1759.9912000440172, 1795.6318954614273, 1798.9100544972698, 1817.8182181781715, 1863.413024897153, 999.0558921818812, 1005.4118340770585, 1038.7247379444452, 1046.2362475393006, 1063.4470075560664, 18611.25554977813, 18926.43220703381, 19260.422187334403, 20135.0, 20187.192512299345, 1015.2334987084852, 1016.2987538598492, 1052.847337136111, 1056.6700425561771, 1062.3572738493256, 10797.575204833624, 11368.147388945697, 11439.027682646902, 11496.482658280398, 11510.597129100608, 489856.0, 491844.0, 496869.0, 501265.0, 506420.0, 99499.50250248847, 102502.0, 106418.46790765997, 107092.0, 108558.45720771345, 1034.3225187502048, 1049.7165765243342, 1080.508368692243, 1088.8257878739364, 1089.7602527444108, 17789.199486022902, 17907.37324193647, 18174.72737908906, 18343.35798247055, 18363.0, 41824.0, 42771.7861410691, 43008.56991430045, 43161.35257971196, 43477.78261108674, 61447.69276153591, 62317.0, 62449.68775156095, 64264.35735642771, 65294.0, 8077.676892924217, 8117.918820811717, 8181.427300089174, 8256.299481674134, 8376.078631350425, 8960.910390896008, 12078.0, 12730.872691272969, 12958.740825183255, 13065.869341306845, 11104.389258590854, 11129.499172537093, 11188.720281993015, 11249.49377278008, 11369.204155709022, 10800.08199303068, 10996.670099897015, 11168.218224724516, 11400.543978240778, 11413.828792568283, 11204.49579768896, 11474.94262528682, 11700.35648039366, 11747.941260293645, 11770.234934729215, 8081.111077781557, 8102.270795628422, 8296.875546866922, 8325.875111873205, 8504.23461888433, 10118.898811011795, 11243.212975091665, 11349.4892729829, 11403.258788178742, 11498.77002459963], "value": 9331.305031482574, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.192448964294, 1544.3050627217751, 1716.1590820497959, 1814.628001259742, 1815.7548730921321, 7759.107702614201, 8578.828423431536, 8628.91371086289, 9118.498482583454, 9188.862167067491, 8689.26141277992, 8920.330975176872, 8922.464652120878, 8945.776355591113, 8975.281977441806, 2325.6860323856317, 2361.347057060056, 2368.0764501844296, 2369.95260094798, 2377.892994815229, 2310.1452462588863, 2356.740758516562, 2357.929262122139, 2387.164492427645, 2389.95220095598, 2362.2204672458047, 2370.525894821034, 2377.667126602277, 2380.8928598213033, 2383.6305372667266, 2287.611106111957, 2310.7920287174234, 2314.6296592545195, 2315.270689732737, 2350.764923507653, 2298.712660917385, 2325.6976593042796, 2338.481184404803, 2339.356676913845, 2345.108858633708, 8802.295816334678, 8949.552522373855, 8955.05971872957, 8978.23684986777, 8987.415817971876, 5266.210068489719, 5351.197320401932, 5398.595105367113, 5400.270963419927, 5404.5676345892625, 3432.9313413731584, 3479.530263414432, 3536.381133301662, 3579.2483578448555, 3599.4600809878466, 1033.1270076785179, 1090.8145615245348, 1123.1071298317856, 1133.5975728616304, 1148.6841118692344, 12171.14801963863, 12196.939015304955, 12261.264324140491, 12274.447649855689, 12298.077644176723, 4471.32930060487, 4493.640508759291, 4616.053708989716, 4618.699784513996, 4624.421947256593, 9728.708138755848, 9763.414195148314, 9786.804263914682, 9794.804103917882, 9797.265205109645, 5198.298229739013, 5243.973780131151, 5270.578353731769, 5274.3670759509105, 5274.657147285414, 4300.225959327351, 4875.561199492063, 4903.975480122576, 5065.569426598707, 5082.822101226439, 3801.125741079567, 3801.334766415866, 3821.8280177392085, 3881.320768865494, 3981.980090099531, 1106.590561492253, 1109.7614012987085, 1115.8382034604938, 1139.5555733264034, 1140.2531341971023, 894.1282249806517, 924.916232131182, 926.4348747264298, 930.4091901642371, 939.736873675372, 5570.749316280777, 5666.320041595035, 5723.112917497794, 5745.109508026263, 5922.2597175353085, 922.0318665401302, 959.098447459386, 979.911807937289, 985.1330828870548, 998.5656239535825, 3630.49173115764, 3910.902227444337, 3915.941260881089, 4028.8187031583648, 4063.817128229237, 10767.407792571326, 11423.600173993871, 11450.599229026944, 11487.770244595062, 11496.885031149748, 10523.84214236787, 10945.179111566664, 11030.944845275882, 11214.66356009321, 11302.830457543143, 8128.59357032146, 8185.140560241089, 8215.876761848576, 8219.671213151407, 8254.0, 5656.660600363992, 5766.711664416678, 5856.267966504187, 5910.320313164041, 6010.1585777991995, 9985.900140998787, 10249.231307651808, 10338.586456541954, 10367.84448233262, 10491.0, 10342.896571034193, 10535.57857685684, 10593.311434756715, 10667.253292269466, 10717.196210284415, 7162.89255661176, 7875.330596899328, 7929.691600885789, 7932.4447288691545, 8055.677772889018, 8519.48883067018, 8546.743597692079, 8548.0, 8609.440386374865, 8694.217520423192, 7639.121501027453, 7878.763637090897, 7881.290683838482, 7930.0, 7943.4836735612, 7639.04511936008, 7701.730439434593, 7769.106552746506, 7787.104482984303, 7889.763307100796, 7105.111861017373, 7159.713611455483, 7162.104736907887, 7184.6048467334795, 7240.637968101679, 4841.564259216688, 4951.480094590016, 5018.247262910447, 5084.618653600921, 5132.230165475208, 4993.076280888047, 5014.548690617862, 5028.773705183348, 5040.495950405077, 5085.949140508547], "value": 4641.447255449386, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.3513626792537, 325.3022267236776, 325.76870422000377, 327.26692209450835, 328.93425302021456, 5496.367917689467, 5569.6658200508, 5679.318481782181, 5686.914696279554, 5714.599978001536, 5556.638818476797, 5570.777168913249, 5588.86027849304, 5594.216809646653, 5612.382637909837, 4328.545502722208, 4348.717333373336, 4360.498542667587, 4369.077605853415, 4369.169857727037, 4086.1419101988618, 4339.0, 4355.473825181403, 4359.149965756678, 4391.187630288391, 4264.78676066197, 4295.914081718363, 4307.267764480046, 4308.116836048603, 4317.892052698676, 4309.008927946574, 4334.5882141196535, 4348.630366418843, 4349.956500435019, 4351.977285337943, 3675.320065787838, 3733.8879833605033, 3771.264603402338, 3782.432635104729, 3795.1214148996146, 5428.83713488596, 5457.754401051962, 5458.399576046631, 5475.206095116226, 5483.506484416422, 4907.975460122677, 4931.778069986859, 4970.478099799505, 4989.251612258154, 4991.101601711691, 5528.253685752412, 5861.794837180678, 5865.912011319833, 5902.881942361171, 5961.552883533709, 4064.1465292288653, 4084.0606660468256, 4089.7750623715683, 4090.427340172378, 4125.690573206992, 6115.379424452479, 6129.295131059941, 6155.322914479404, 6177.474914632262, 6211.285702144266, 4102.0, 4127.669786417079, 4152.211079894811, 4167.916641667211, 4173.353130264813, 4209.452771139732, 4219.514755803122, 4222.936655950163, 4240.300350442204, 4256.4679415073115, 4887.413510378778, 4913.238448040559, 4914.803407863646, 4918.926216106761, 4957.206846904478, 5769.653820770767, 5835.883282334329, 5850.619709718854, 5912.0, 5993.940060599425, 1445.4868521674864, 1448.6740483391152, 1470.3897882378908, 1470.955871323862, 1474.9410023598937, 906.1934877958622, 923.4643906534194, 935.9017303183203, 945.6122989574242, 945.7635591102224, 5094.61790365724, 5098.796048158032, 5115.462876397999, 5143.819966301162, 5153.690778553224, 5322.973385133049, 5337.331334332834, 5349.384820745664, 5376.301080859464, 5417.864553386197, 5053.9241911371355, 5084.0, 5093.388793344822, 5238.816641417532, 5703.7148142592705, 3207.550942868, 3293.4236508610898, 3296.538484612156, 3310.8675652974093, 3312.271300313927, 6109.327973922865, 6311.905321420182, 6333.429991300713, 6337.302896681362, 6403.839904002437, 6269.457756367638, 6273.309935907047, 6284.151639528696, 6286.717097730613, 6295.527835412362, 6263.592866463665, 6269.561130720897, 6308.3060863305, 6357.300696923335, 6392.584482008655, 5881.176635271151, 6025.66858822769, 6073.11733362659, 6111.694415279308, 6119.602225855305, 5999.5500337473995, 6011.849703757441, 6067.24159480065, 6067.423594758491, 6080.179175811209, 6018.21763170785, 6255.37446255389, 6293.496520278458, 6363.87272254543, 6402.839929001812, 4220.430241917302, 4258.978705106455, 4269.146170765797, 4277.310462367285, 4299.656027517729, 6133.969330153321, 6246.9062964054665, 6268.0, 6277.027060805583, 6277.18396608438, 5778.595498315246, 5953.464188223081, 5965.9105113422465, 6038.63768173911, 6073.635581865102, 5934.43622855828, 5937.970310148422, 5967.1944287522365, 5973.970130149321, 5976.462118409364, 5762.337331206965, 5791.189233507396, 5822.272215973004, 5839.941600583939, 5876.882462350814, 5944.108383742473, 5963.970180149072, 5991.670458124845, 6014.9097763534455, 6016.052134454174, 5805.3614102447855, 5806.506446952057, 5847.421196277067, 5871.588988770745, 5891.23413956183], "value": 4432.978209875973, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.1520358013346, 806.3871457692152, 818.5334359415136, 828.8549503836826, 830.1357182316318, 14420.567382978514, 15435.845641543596, 15508.844911550881, 15629.296681649324, 15645.452409165682, 14379.784303235434, 14403.351849166786, 14417.134971901693, 14433.494827681006, 14460.132392056485, 661.8345413646589, 770.218228498075, 782.6869252299069, 796.5977181523323, 797.6849144587884, 790.5533373643901, 791.588374045496, 795.8249185179251, 796.3191471292049, 796.4464697035562, 841.7979684875647, 844.7254642241296, 845.0619812008665, 846.1792061700141, 847.9576021198947, 833.7998880268752, 842.751388340441, 842.9157084291522, 843.2663582683043, 844.4637655089032, 611.0406661541382, 611.1627070912833, 611.1749138662814, 611.8256296955346, 612.494691879197, 14625.92687036558, 14679.63300917475, 14681.853181468156, 14683.706325873423, 14710.411583536752, 14526.0, 14996.700065998728, 15183.0, 15237.16195609241, 15372.385104595915, 12023.579174728842, 12184.329861857594, 12230.205036672587, 12316.076294277966, 12866.035047371392, 3692.2615476904457, 3791.279656865187, 3791.374423220165, 3814.256220037094, 4441.15618032576, 60608.69695651538, 61046.38953610496, 61625.38374616241, 62273.37726622766, 62976.68511657458, 11083.50124244411, 11103.777924441465, 11186.664400068012, 11195.104391648649, 11334.60328888485, 19414.611707765765, 19477.513062173275, 19515.414537563895, 19545.0, 19563.217471301272, 8549.871751923725, 8657.870131948026, 8688.391812573185, 8746.518941458153, 8825.338099642553, 4711.128441238382, 4720.527947205252, 4756.762161891892, 4871.902561948741, 4908.754562271872, 3317.3365326934227, 3444.190615205425, 3468.722502199818, 3469.2714529948994, 3475.287566048954, 642.2549842183139, 644.1658052821607, 649.0588646462649, 658.2857599504591, 673.592476551689, 744.162816831065, 751.2412463411994, 773.4469854054378, 773.6943907156755, 778.5990215039259, 9655.662051828152, 9865.950670246602, 9914.206863450907, 9960.950195248977, 10021.549030293654, 718.1633397092434, 723.8588475247382, 725.856775578466, 736.1975446762947, 736.6316841579211, 4477.977610111929, 4693.601043911238, 4696.600788932979, 4721.1501929652995, 4753.31076993834, 69309.0, 71637.64181179061, 71937.0, 74651.62674186703, 74710.0, 31488.370232595676, 32026.83986580052, 56435.71782141063, 59817.40182598205, 68129.65935170293, 60256.39743602595, 60733.69633151806, 60943.0, 61971.69014154901, 62136.06795898065, 9489.383190092622, 9728.173105285847, 9876.802463950537, 9907.801843963225, 9999.800004000024, 39098.0, 39167.60832391639, 39223.2155356897, 39829.40255896106, 40075.599244007186, 51285.48714512807, 54397.45602543924, 54479.0, 54863.0, 55694.72152639211, 12503.812442813547, 12666.303353315656, 12911.806322904977, 13231.603051908458, 13247.20516768997, 13682.31588420595, 13906.167842296878, 14191.0, 14243.21662308583, 14552.563423097325, 13141.605751827461, 13315.334233288491, 13429.127106738031, 13649.38577763983, 14065.296735163405, 12200.938995304967, 12204.0, 12375.75248495007, 12401.353993984283, 12716.491340346283, 12649.367531623566, 13067.673308166992, 13127.34363281813, 13216.933915330746, 13241.602751917459, 10621.309614875008, 10782.406967616856, 10822.296550724177, 11132.0, 11327.886721133013, 11163.832542512031, 11301.281670331464, 11304.773904522028, 11460.19778615489, 11475.713107172387], "value": 7067.161842815136, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 107.0, 109.0, 111.0, 111.0, 105.0, 109.0, 110.0, 111.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 161.0, 216.0, 127.0, 154.0, 152.0, 134.0, 182.0, 125.0], "value": 153.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 163.0, 167.0, 175.0, 163.0, 164.0, 162.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 158.0, 75.0, 76.0, 75.0, 75.0, 172.0, 76.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 191.0, 93.0, 92.0, 94.0, 148.0, 91.0, 93.0, 162.0], "value": 114.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 117.0, 118.0, 119.0, 119.0, 118.0, 117.0, 118.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 57.0, 55.0, 55.0, 58.0, 110.0, 53.0, 53.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 61.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 78.0, 79.0, 79.0, 79.0, 79.0, 78.0, 80.0, 81.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 138.0, 120.0, 119.0, 128.0, 120.0, 130.0, 210.0, 125.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 165.0, 159.0, 156.0, 162.0, 157.0, 161.0, 160.0, 158.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 62.0, 65.0, 65.0, 62.0, 62.0, 62.0, 61.0, 62.0], "value": 63.0, "unit": "score"}]}]} 23:20:01 INFO - Return code: 0 23:20:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:20:01 ERROR - # TBPL SUCCESS # 23:20:01 INFO - Running post-action listener: _resource_record_post_action 23:20:01 INFO - ##### 23:20:01 INFO - ##### Finished run-tests step (success) 23:20:01 INFO - ##### 23:20:01 INFO - Running post-run listener: _resource_record_post_run 23:20:01 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 539693056; Read time: 12; Write time: 383616 23:20:01 INFO - TinderboxPrint: CPU usage
13.4% 23:20:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:20:01 INFO - TinderboxPrint: I/O write bytes / time
539,693,056 / 383,616 23:20:01 INFO - TinderboxPrint: CPU idle
8,225.8 (86.6%) 23:20:01 INFO - TinderboxPrint: CPU user
1,220.9 (12.9%) 23:20:01 INFO - TinderboxPrint: Swap in / out
0 / 0 23:20:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 247214080; Read time: 0; Write time: 215152 23:20:01 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292478976; Read time: 12; Write time: 168464 23:20:01 INFO - Running post-run listener: _upload_blobber_files 23:20:01 INFO - Blob upload gear active. 23:20:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:20:01 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 23:20:01 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'] 23:20:01 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 23:20:02 INFO - (blobuploader) - INFO - Open directory for files ... 23:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:03 INFO - (blobuploader) - INFO - Done attempting. 23:20:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:20:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:07 INFO - (blobuploader) - INFO - Done attempting. 23:20:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:20:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:20:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:08 INFO - (blobuploader) - INFO - Done attempting. 23:20:08 INFO - (blobuploader) - INFO - Iteration through files over. 23:20:08 INFO - Return code: 0 23:20:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:20:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:20:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be634d06e64cf5bdadcc54bb4e2b099016fda24d0021078099c20181e7170d7060263f47b939f6ac61f2b5b1c64c9ef3e31151cb34025272afe75f9e20d7ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c45da72ed749b9da3654314212618027a187226af1c06fe7b3ace53c404a7e0953c418afa7241ca906af6720253c6ce86e77856ed838f5628f43d4348c22d587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa7b73d9f8658960076aa65546f0d606ed65a36c243e6ebfb72c701572e54f87138f1814c6c2f3e978a00c3f0ab3fe3a083fdf24f310cf3124d3a6dead273af1"} 23:20:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:20:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:20:08 INFO - Contents: 23:20:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be634d06e64cf5bdadcc54bb4e2b099016fda24d0021078099c20181e7170d7060263f47b939f6ac61f2b5b1c64c9ef3e31151cb34025272afe75f9e20d7ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c45da72ed749b9da3654314212618027a187226af1c06fe7b3ace53c404a7e0953c418afa7241ca906af6720253c6ce86e77856ed838f5628f43d4348c22d587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa7b73d9f8658960076aa65546f0d606ed65a36c243e6ebfb72c701572e54f87138f1814c6c2f3e978a00c3f0ab3fe3a083fdf24f310cf3124d3a6dead273af1"} 23:20:08 INFO - Running post-run listener: copy_logs_to_upload_dir 23:20:08 INFO - Copying logs to upload dir... 23:20:08 INFO - mkdir: /builds/slave/test/build/upload/logs 23:20:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.800227 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-19 23:20:07.722211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 23:20:07.725959) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be634d06e64cf5bdadcc54bb4e2b099016fda24d0021078099c20181e7170d7060263f47b939f6ac61f2b5b1c64c9ef3e31151cb34025272afe75f9e20d7ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c45da72ed749b9da3654314212618027a187226af1c06fe7b3ace53c404a7e0953c418afa7241ca906af6720253c6ce86e77856ed838f5628f43d4348c22d587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa7b73d9f8658960076aa65546f0d606ed65a36c243e6ebfb72c701572e54f87138f1814c6c2f3e978a00c3f0ab3fe3a083fdf24f310cf3124d3a6dead273af1"} build_url:https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/XyxYN_omThyuEW0u-STcpg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7be634d06e64cf5bdadcc54bb4e2b099016fda24d0021078099c20181e7170d7060263f47b939f6ac61f2b5b1c64c9ef3e31151cb34025272afe75f9e20d7ca3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c45da72ed749b9da3654314212618027a187226af1c06fe7b3ace53c404a7e0953c418afa7241ca906af6720253c6ce86e77856ed838f5628f43d4348c22d587", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa7b73d9f8658960076aa65546f0d606ed65a36c243e6ebfb72c701572e54f87138f1814c6c2f3e978a00c3f0ab3fe3a083fdf24f310cf3124d3a6dead273af1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 23:20:07.754678) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 23:20:07.755120) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463723474.418362-1261424376 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 23:20:07.836531) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 23:20:07.836887) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 23:20:07.837209) ========= ========= Total master_lag: 0.18 =========