builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1464170953.91 results: success (0) buildid: 20160525001739 builduid: 11ef02cf7e04492eaee844a730e52845 revision: 97b22d33cd78 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:13.905660) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:13.906553) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:13.907027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:13.926700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:13.927276) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-25 03:09:14-- 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% 66.3M=0s 2016-05-25 03:09:14 (66.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081885 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.024982) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.025512) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018717 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.079884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.080422) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 97b22d33cd78 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 97b22d33cd78 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-25 03:09:14,326 Setting DEBUG logging. 2016-05-25 03:09:14,326 attempt 1/10 2016-05-25 03:09:14,326 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/97b22d33cd78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 03:09:14,763 unpacking tar archive at: mozilla-inbound-97b22d33cd78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.765738 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.862363) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.862947) ========= script_repo_revision: 97b22d33cd78 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.863635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.864078) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 03:09:14.875471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-05-25 03:09:14.875868) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:09:15 INFO - MultiFileLogger online at 20160525 03:09:15 in /builds/slave/test 03:09:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 03:09:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:09:15 INFO - {'append_to_log': False, 03:09:15 INFO - 'base_work_dir': '/builds/slave/test', 03:09:15 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 03:09:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:09:15 INFO - 'branch': 'Mozilla-Inbound', 03:09:15 INFO - 'buildbot_json_path': 'buildprops.json', 03:09:15 INFO - 'config_files': ('talos/linux_config.py',), 03:09:15 INFO - 'default_actions': ('clobber', 03:09:15 INFO - 'read-buildbot-config', 03:09:15 INFO - 'download-and-extract', 03:09:15 INFO - 'populate-webroot', 03:09:15 INFO - 'create-virtualenv', 03:09:15 INFO - 'install', 03:09:15 INFO - 'run-tests'), 03:09:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:09:15 INFO - 'download_minidump_stackwalk': True, 03:09:15 INFO - 'download_symbols': 'ondemand', 03:09:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:09:15 INFO - 'tooltool.py': '/tools/tooltool.py', 03:09:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:09:15 INFO - '/tools/misc-python/virtualenv.py')}, 03:09:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:09:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:09:15 INFO - 'installer_path': 'installer.exe', 03:09:15 INFO - 'log_level': 'info', 03:09:15 INFO - 'log_name': 'talos', 03:09:15 INFO - 'log_to_console': True, 03:09:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:09:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:09:15 INFO - 'opt_config_files': (), 03:09:15 INFO - 'pip_index': False, 03:09:15 INFO - 'sps_profile': False, 03:09:15 INFO - 'sps_profile_interval': 0, 03:09:15 INFO - 'suite': 'dromaeojs', 03:09:15 INFO - 'system_bits': '32', 03:09:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:09:15 INFO - 'title': 'talos-linux64-ix-041', 03:09:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:09:15 INFO - 'use_talos_json': True, 03:09:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:09:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:09:15 INFO - 'work_dir': 'build'} 03:09:15 INFO - ##### 03:09:15 INFO - ##### Running clobber step. 03:09:15 INFO - ##### 03:09:15 INFO - Running pre-action listener: _resource_record_pre_action 03:09:15 INFO - Running main action method: clobber 03:09:15 INFO - rmtree: /builds/slave/test/build 03:09:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:09:15 INFO - Running post-action listener: _resource_record_post_action 03:09:15 INFO - ##### 03:09:15 INFO - ##### Finished clobber step (success) 03:09:15 INFO - ##### 03:09:15 INFO - ##### 03:09:15 INFO - ##### Running read-buildbot-config step. 03:09:15 INFO - ##### 03:09:15 INFO - Running pre-action listener: _resource_record_pre_action 03:09:15 INFO - Running main action method: read_buildbot_config 03:09:15 INFO - Using buildbot properties: 03:09:15 INFO - { 03:09:15 INFO - "project": "", 03:09:15 INFO - "product": "firefox", 03:09:15 INFO - "script_repo_revision": "production", 03:09:15 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 03:09:15 INFO - "repository": "", 03:09:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 03:09:15 INFO - "buildid": "20160525001739", 03:09:15 INFO - "pgo_build": "True", 03:09:15 INFO - "basedir": "/builds/slave/test", 03:09:15 INFO - "buildnumber": 1063, 03:09:15 INFO - "slavename": "talos-linux64-ix-041", 03:09:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:09:15 INFO - "platform": "ubuntu64_hw", 03:09:15 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 03:09:15 INFO - "branch": "mozilla-inbound", 03:09:15 INFO - "repo_path": "integration/mozilla-inbound", 03:09:15 INFO - "revision": "97b22d33cd78", 03:09:15 INFO - "stage_platform": "linux64-pgo", 03:09:15 INFO - "builduid": "11ef02cf7e04492eaee844a730e52845", 03:09:15 INFO - "slavebuilddir": "test" 03:09:15 INFO - } 03:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 03:09:15 INFO - Running post-action listener: _resource_record_post_action 03:09:15 INFO - ##### 03:09:15 INFO - ##### Finished read-buildbot-config step (success) 03:09:15 INFO - ##### 03:09:15 INFO - ##### 03:09:15 INFO - ##### Running download-and-extract step. 03:09:15 INFO - ##### 03:09:15 INFO - Running pre-action listener: _resource_record_pre_action 03:09:15 INFO - Running main action method: download_and_extract 03:09:15 INFO - mkdir: /builds/slave/test/build/tests 03:09:15 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')]} 03:09:15 INFO - https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:09:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 03:09:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 03:09:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:19 INFO - Downloaded 1361 bytes. 03:09:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 03:09:19 INFO - Using the following test package requirements: 03:09:19 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 03:09:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:09:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:09:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'jsshell-linux-x86_64.zip'], 03:09:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:09:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 03:09:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:09:19 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:09:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:09:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:19 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:09:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:09:19 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')]} 03:09:19 INFO - https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:21 INFO - Downloaded 24421343 bytes. 03:09:21 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'] 03:09:21 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 03:09:23 INFO - Return code: 0 03:09:23 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 03:09:23 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')]} 03:09:23 INFO - https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:25 INFO - Downloaded 24421343 bytes. 03:09:25 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'] 03:09:25 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 03:09:26 INFO - Return code: 0 03:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:26 INFO - https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:09:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 03:09:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 03:09:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:28 INFO - Downloaded 10834034 bytes. 03:09:28 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'] 03:09:28 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 03:09:28 INFO - Return code: 0 03:09:28 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')]} 03:09:28 INFO - https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:09:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:09:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:09:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eXlwUhHNSB6JX3dRsgt3lA/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 03:09:32 INFO - Downloaded 58662868 bytes. 03:09:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:09:32 INFO - mkdir: /builds/slave/test/properties 03:09:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:09:32 INFO - Writing to file /builds/slave/test/properties/build_url 03:09:32 INFO - Contents: 03:09:32 INFO - build_url:https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:09:33 INFO - Running post-action listener: _resource_record_post_action 03:09:33 INFO - Running post-action listener: set_extra_try_arguments 03:09:33 INFO - ##### 03:09:33 INFO - ##### Finished download-and-extract step (success) 03:09:33 INFO - ##### 03:09:33 INFO - ##### 03:09:33 INFO - ##### Running populate-webroot step. 03:09:33 INFO - ##### 03:09:33 INFO - Running pre-action listener: _resource_record_pre_action 03:09:33 INFO - Running main action method: populate_webroot 03:09:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:09:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:09:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:09:33 INFO - '--tppagecycles', 03:09:33 INFO - '7'], 03:09:33 INFO - 'tests': ['tsvgm']}}, 03:09:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:09:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:09:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:09:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:09:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['damp', 'tps']}, 03:09:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'tests': ['damp', 'tps']}, 03:09:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['dromaeo_dom']}, 03:09:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:09:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['a11yr', 03:09:33 INFO - 'ts_paint', 03:09:33 INFO - 'tpaint', 03:09:33 INFO - 'sessionrestore', 03:09:33 INFO - 'sessionrestore_no_auto_restore', 03:09:33 INFO - 'tabpaint']}, 03:09:33 INFO - 'other-e10s': {'tests': ['a11yr', 03:09:33 INFO - 'ts_paint', 03:09:33 INFO - 'tpaint', 03:09:33 INFO - 'sessionrestore', 03:09:33 INFO - 'sessionrestore_no_auto_restore', 03:09:33 INFO - 'tabpaint']}, 03:09:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:09:33 INFO - 'ts_paint', 03:09:33 INFO - 'tpaint', 03:09:33 INFO - 'sessionrestore', 03:09:33 INFO - 'sessionrestore_no_auto_restore', 03:09:33 INFO - 'tabpaint']}, 03:09:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:09:33 INFO - 'ts_paint', 03:09:33 INFO - 'tpaint', 03:09:33 INFO - 'sessionrestore', 03:09:33 INFO - 'sessionrestore_no_auto_restore', 03:09:33 INFO - 'tabpaint']}, 03:09:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['a11yr', 03:09:33 INFO - 'ts_paint', 03:09:33 INFO - 'tpaint', 03:09:33 INFO - 'sessionrestore', 03:09:33 INFO - 'sessionrestore_no_auto_restore', 03:09:33 INFO - 'tabpaint']}, 03:09:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['a11yr', 03:09:33 INFO - 'ts_paint', 03:09:33 INFO - 'tpaint', 03:09:33 INFO - 'sessionrestore', 03:09:33 INFO - 'sessionrestore_no_auto_restore', 03:09:33 INFO - 'tabpaint']}, 03:09:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['tsvgx', 03:09:33 INFO - 'tsvgr_opacity', 03:09:33 INFO - 'tart', 03:09:33 INFO - 'tscrollx', 03:09:33 INFO - 'cart']}, 03:09:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:09:33 INFO - 'tsvgr_opacity', 03:09:33 INFO - 'tart', 03:09:33 INFO - 'tscrollx', 03:09:33 INFO - 'cart']}, 03:09:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'talos_options': ['--disable-e10s'], 03:09:33 INFO - 'tests': ['tp5o']}, 03:09:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'tests': ['tp5o']}, 03:09:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'talos_options': ['--disable-e10s', 03:09:33 INFO - '--xperf_path', 03:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:33 INFO - 'tests': ['tp5n']}, 03:09:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:33 INFO - 'talos_options': ['--xperf_path', 03:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:33 INFO - 'tests': ['tp5n']}}, 03:09:33 INFO - 'talos.zip': {'path': '', 03:09:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:09:33 INFO - Running post-action listener: _resource_record_post_action 03:09:33 INFO - ##### 03:09:33 INFO - ##### Finished populate-webroot step (success) 03:09:33 INFO - ##### 03:09:33 INFO - ##### 03:09:33 INFO - ##### Running create-virtualenv step. 03:09:33 INFO - ##### 03:09:33 INFO - Running pre-action listener: _resource_record_pre_action 03:09:33 INFO - Running main action method: create_virtualenv 03:09:33 INFO - Creating virtualenv /builds/slave/test/build/venv 03:09:33 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 03:09:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:09:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:09:33 INFO - Using real prefix '/usr' 03:09:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:09:33 INFO - Installing distribute.............................................................................................................................................................................................done. 03:09:35 INFO - Installing pip.................done. 03:09:35 INFO - Return code: 0 03:09:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:35 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:35 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 03:09:35 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 03:09:35 INFO - Using env: {'DISPLAY': ':0', 03:09:35 INFO - 'HOME': '/home/cltbld', 03:09:35 INFO - 'LANG': 'en_US.UTF-8', 03:09:35 INFO - 'LANGUAGE': 'en_US:en', 03:09:35 INFO - 'LOGNAME': 'cltbld', 03:09:35 INFO - 'MAIL': '/var/mail/cltbld', 03:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:35 INFO - 'MOZ_NO_REMOTE': '1', 03:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:35 INFO - 'NO_EM_RESTART': '1', 03:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:35 INFO - 'PWD': '/builds/slave/test', 03:09:35 INFO - 'SHELL': '/bin/bash', 03:09:35 INFO - 'SHLVL': '1', 03:09:35 INFO - 'TERM': 'linux', 03:09:35 INFO - 'TMOUT': '86400', 03:09:35 INFO - 'USER': 'cltbld', 03:09:35 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:35 INFO - '_': '/tools/buildbot/bin/python'} 03:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:35 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 03:09:35 INFO - Cleaning up... 03:09:35 INFO - Return code: 0 03:09:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:35 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:35 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 03:09:35 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 03:09:35 INFO - Using env: {'DISPLAY': ':0', 03:09:35 INFO - 'HOME': '/home/cltbld', 03:09:35 INFO - 'LANG': 'en_US.UTF-8', 03:09:35 INFO - 'LANGUAGE': 'en_US:en', 03:09:35 INFO - 'LOGNAME': 'cltbld', 03:09:35 INFO - 'MAIL': '/var/mail/cltbld', 03:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:35 INFO - 'MOZ_NO_REMOTE': '1', 03:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:35 INFO - 'NO_EM_RESTART': '1', 03:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:35 INFO - 'PWD': '/builds/slave/test', 03:09:35 INFO - 'SHELL': '/bin/bash', 03:09:35 INFO - 'SHLVL': '1', 03:09:35 INFO - 'TERM': 'linux', 03:09:35 INFO - 'TMOUT': '86400', 03:09:35 INFO - 'USER': 'cltbld', 03:09:35 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:35 INFO - '_': '/tools/buildbot/bin/python'} 03:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:36 INFO - Downloading/unpacking psutil>=3.1.1 03:09:36 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 03:09:36 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 03:09:36 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 03:09:36 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 03:09:36 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 03:09:36 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 03:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:09:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:38 INFO - Installing collected packages: psutil 03:09:38 INFO - Running setup.py install for psutil 03:09:38 INFO - building 'psutil._psutil_linux' extension 03:09:38 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 03:09:38 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 03:09:38 INFO - building 'psutil._psutil_posix' extension 03:09:38 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 03:09:38 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 03:09:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:38 INFO - Successfully installed psutil 03:09:38 INFO - Cleaning up... 03:09:38 INFO - Return code: 0 03:09:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:09:38 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')]} 03:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:38 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')]} 03:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:38 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:38 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 03:09:38 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 03:09:38 INFO - Using env: {'DISPLAY': ':0', 03:09:38 INFO - 'HOME': '/home/cltbld', 03:09:38 INFO - 'LANG': 'en_US.UTF-8', 03:09:38 INFO - 'LANGUAGE': 'en_US:en', 03:09:38 INFO - 'LOGNAME': 'cltbld', 03:09:38 INFO - 'MAIL': '/var/mail/cltbld', 03:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:38 INFO - 'MOZ_NO_REMOTE': '1', 03:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:38 INFO - 'NO_EM_RESTART': '1', 03:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:38 INFO - 'PWD': '/builds/slave/test', 03:09:38 INFO - 'SHELL': '/bin/bash', 03:09:38 INFO - 'SHLVL': '1', 03:09:38 INFO - 'TERM': 'linux', 03:09:38 INFO - 'TMOUT': '86400', 03:09:38 INFO - 'USER': 'cltbld', 03:09:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:38 INFO - '_': '/tools/buildbot/bin/python'} 03:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:09:38 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 03:09:38 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 03:09:38 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 03:09:38 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 03:09:38 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 03:09:38 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 03:09:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:09:40 INFO - Installing collected packages: mozsystemmonitor 03:09:40 INFO - Running setup.py install for mozsystemmonitor 03:09:40 INFO - Successfully installed mozsystemmonitor 03:09:40 INFO - Cleaning up... 03:09:40 INFO - Return code: 0 03:09:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:40 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:40 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 03:09:40 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 03:09:40 INFO - Using env: {'DISPLAY': ':0', 03:09:40 INFO - 'HOME': '/home/cltbld', 03:09:40 INFO - 'LANG': 'en_US.UTF-8', 03:09:40 INFO - 'LANGUAGE': 'en_US:en', 03:09:40 INFO - 'LOGNAME': 'cltbld', 03:09:40 INFO - 'MAIL': '/var/mail/cltbld', 03:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:40 INFO - 'MOZ_NO_REMOTE': '1', 03:09:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:40 INFO - 'NO_EM_RESTART': '1', 03:09:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:40 INFO - 'PWD': '/builds/slave/test', 03:09:40 INFO - 'SHELL': '/bin/bash', 03:09:40 INFO - 'SHLVL': '1', 03:09:40 INFO - 'TERM': 'linux', 03:09:40 INFO - 'TMOUT': '86400', 03:09:40 INFO - 'USER': 'cltbld', 03:09:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:40 INFO - '_': '/tools/buildbot/bin/python'} 03:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:41 INFO - Downloading/unpacking blobuploader==1.2.4 03:09:41 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 03:09:41 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 03:09:41 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 03:09:41 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 03:09:41 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 03:09:41 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 03:09:43 INFO - Downloading blobuploader-1.2.4.tar.gz 03:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:09:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:09:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:43 INFO - Downloading docopt-0.6.1.tar.gz 03:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:09:43 INFO - Installing collected packages: blobuploader, requests, docopt 03:09:43 INFO - Running setup.py install for blobuploader 03:09:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:09:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:09:43 INFO - Running setup.py install for requests 03:09:44 INFO - Running setup.py install for docopt 03:09:44 INFO - Successfully installed blobuploader requests docopt 03:09:44 INFO - Cleaning up... 03:09:44 INFO - Return code: 0 03:09:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:44 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')]} 03:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:44 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')]} 03:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:44 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:44 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 03:09:44 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 03:09:44 INFO - Using env: {'DISPLAY': ':0', 03:09:44 INFO - 'HOME': '/home/cltbld', 03:09:44 INFO - 'LANG': 'en_US.UTF-8', 03:09:44 INFO - 'LANGUAGE': 'en_US:en', 03:09:44 INFO - 'LOGNAME': 'cltbld', 03:09:44 INFO - 'MAIL': '/var/mail/cltbld', 03:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:44 INFO - 'MOZ_NO_REMOTE': '1', 03:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:44 INFO - 'NO_EM_RESTART': '1', 03:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:44 INFO - 'PWD': '/builds/slave/test', 03:09:44 INFO - 'SHELL': '/bin/bash', 03:09:44 INFO - 'SHLVL': '1', 03:09:44 INFO - 'TERM': 'linux', 03:09:44 INFO - 'TMOUT': '86400', 03:09:44 INFO - 'USER': 'cltbld', 03:09:44 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:44 INFO - '_': '/tools/buildbot/bin/python'} 03:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:44 INFO - Running setup.py (path:/tmp/pip-bjWneP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:44 INFO - Running setup.py (path:/tmp/pip-GfJW3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:44 INFO - Running setup.py (path:/tmp/pip-AzsFKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:44 INFO - Running setup.py (path:/tmp/pip-qDU8OU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:44 INFO - Running setup.py (path:/tmp/pip-nuqBov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:45 INFO - Running setup.py (path:/tmp/pip-_KgYYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:45 INFO - Running setup.py (path:/tmp/pip-LSKGI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:45 INFO - Running setup.py (path:/tmp/pip-MjplK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:45 INFO - Running setup.py (path:/tmp/pip-3kdLX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:45 INFO - Running setup.py (path:/tmp/pip-S3zCua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:45 INFO - Running setup.py (path:/tmp/pip-mKWYi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:45 INFO - Running setup.py (path:/tmp/pip-HVbo2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:45 INFO - Running setup.py (path:/tmp/pip-esnfCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:45 INFO - Running setup.py (path:/tmp/pip-xz3Pwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:45 INFO - Running setup.py (path:/tmp/pip-0oYtGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:45 INFO - Running setup.py (path:/tmp/pip-LRN_wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:45 INFO - Running setup.py (path:/tmp/pip-f419Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:45 INFO - Running setup.py install for manifestparser 03:09:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:45 INFO - Running setup.py install for mozcrash 03:09:46 INFO - Running setup.py install for mozdebug 03:09:46 INFO - Running setup.py install for mozdevice 03:09:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Running setup.py install for mozfile 03:09:46 INFO - Running setup.py install for mozhttpd 03:09:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Running setup.py install for mozinfo 03:09:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Running setup.py install for mozInstall 03:09:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Running setup.py install for mozleak 03:09:46 INFO - Running setup.py install for mozlog 03:09:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:46 INFO - Running setup.py install for moznetwork 03:09:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:47 INFO - Running setup.py install for mozprocess 03:09:47 INFO - Running setup.py install for mozprofile 03:09:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:47 INFO - Running setup.py install for mozrunner 03:09:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:47 INFO - Running setup.py install for mozscreenshot 03:09:47 INFO - Running setup.py install for moztest 03:09:47 INFO - Running setup.py install for mozversion 03:09:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:47 INFO - Cleaning up... 03:09:47 INFO - Return code: 0 03:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:47 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:47 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 03:09:47 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 03:09:47 INFO - Using env: {'DISPLAY': ':0', 03:09:47 INFO - 'HOME': '/home/cltbld', 03:09:47 INFO - 'LANG': 'en_US.UTF-8', 03:09:47 INFO - 'LANGUAGE': 'en_US:en', 03:09:47 INFO - 'LOGNAME': 'cltbld', 03:09:47 INFO - 'MAIL': '/var/mail/cltbld', 03:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:47 INFO - 'MOZ_NO_REMOTE': '1', 03:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:47 INFO - 'NO_EM_RESTART': '1', 03:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:47 INFO - 'PWD': '/builds/slave/test', 03:09:47 INFO - 'SHELL': '/bin/bash', 03:09:47 INFO - 'SHLVL': '1', 03:09:47 INFO - 'TERM': 'linux', 03:09:47 INFO - 'TMOUT': '86400', 03:09:47 INFO - 'USER': 'cltbld', 03:09:47 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:47 INFO - '_': '/tools/buildbot/bin/python'} 03:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:47 INFO - Running setup.py (path:/tmp/pip-U3w5Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:47 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)) 03:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:47 INFO - Running setup.py (path:/tmp/pip-oYRGI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:48 INFO - Running setup.py (path:/tmp/pip-gAnjSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:48 INFO - Running setup.py (path:/tmp/pip-HC12_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:48 INFO - Running setup.py (path:/tmp/pip-fulNVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:48 INFO - Running setup.py (path:/tmp/pip-dTFLnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:48 INFO - Running setup.py (path:/tmp/pip-CD0Z0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:48 INFO - Running setup.py (path:/tmp/pip-91KGYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:48 INFO - Running setup.py (path:/tmp/pip-5qOm9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:48 INFO - Running setup.py (path:/tmp/pip-rLCmzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:48 INFO - Running setup.py (path:/tmp/pip-jelXMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:48 INFO - Running setup.py (path:/tmp/pip-loIEWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:48 INFO - Running setup.py (path:/tmp/pip-okntNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:48 INFO - Running setup.py (path:/tmp/pip-I2vOFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:48 INFO - Running setup.py (path:/tmp/pip-Net2U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:48 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)) 03:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:48 INFO - Running setup.py (path:/tmp/pip-RbdWG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:49 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)) 03:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:49 INFO - Running setup.py (path:/tmp/pip-czifJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:49 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)) 03:09:49 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)) 03:09:49 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)) 03:09:49 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)) 03:09:49 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)) 03:09:49 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)) 03:09:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:51 INFO - Downloading blessings-1.6.tar.gz 03:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:09:51 INFO - Installing collected packages: blessings 03:09:51 INFO - Running setup.py install for blessings 03:09:51 INFO - Successfully installed blessings 03:09:51 INFO - Cleaning up... 03:09:51 INFO - Return code: 0 03:09:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:09:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:51 INFO - Reading from file tmpfile_stdout 03:09:51 INFO - Current package versions: 03:09:51 INFO - argparse == 1.2.1 03:09:51 INFO - blessings == 1.6 03:09:51 INFO - blobuploader == 1.2.4 03:09:51 INFO - docopt == 0.6.1 03:09:51 INFO - manifestparser == 1.1 03:09:51 INFO - mozInstall == 1.12 03:09:51 INFO - mozcrash == 0.17 03:09:51 INFO - mozdebug == 0.1 03:09:51 INFO - mozdevice == 0.48 03:09:51 INFO - mozfile == 1.2 03:09:51 INFO - mozhttpd == 0.7 03:09:51 INFO - mozinfo == 0.9 03:09:51 INFO - mozleak == 0.1 03:09:51 INFO - mozlog == 3.2 03:09:51 INFO - moznetwork == 0.27 03:09:51 INFO - mozprocess == 0.22 03:09:51 INFO - mozprofile == 0.28 03:09:51 INFO - mozrunner == 6.11 03:09:51 INFO - mozscreenshot == 0.1 03:09:51 INFO - mozsystemmonitor == 0.3 03:09:51 INFO - moztest == 0.7 03:09:51 INFO - mozversion == 1.4 03:09:51 INFO - psutil == 3.1.1 03:09:51 INFO - requests == 1.2.3 03:09:51 INFO - wsgiref == 0.1.2 03:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:51 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 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:51 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 03:09:51 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 03:09:51 INFO - Using env: {'DISPLAY': ':0', 03:09:51 INFO - 'HOME': '/home/cltbld', 03:09:51 INFO - 'LANG': 'en_US.UTF-8', 03:09:51 INFO - 'LANGUAGE': 'en_US:en', 03:09:51 INFO - 'LOGNAME': 'cltbld', 03:09:51 INFO - 'MAIL': '/var/mail/cltbld', 03:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:51 INFO - 'MOZ_NO_REMOTE': '1', 03:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:51 INFO - 'NO_EM_RESTART': '1', 03:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:51 INFO - 'PWD': '/builds/slave/test', 03:09:51 INFO - 'SHELL': '/bin/bash', 03:09:51 INFO - 'SHLVL': '1', 03:09:51 INFO - 'TERM': 'linux', 03:09:51 INFO - 'TMOUT': '86400', 03:09:51 INFO - 'USER': 'cltbld', 03:09:51 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:51 INFO - '_': '/tools/buildbot/bin/python'} 03:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 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)) 03:09:51 INFO - Cleaning up... 03:09:51 INFO - Return code: 0 03:09:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:09:51 INFO - Using env: {'DISPLAY': ':0', 03:09:51 INFO - 'HOME': '/home/cltbld', 03:09:51 INFO - 'LANG': 'en_US.UTF-8', 03:09:51 INFO - 'LANGUAGE': 'en_US:en', 03:09:51 INFO - 'LOGNAME': 'cltbld', 03:09:51 INFO - 'MAIL': '/var/mail/cltbld', 03:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:51 INFO - 'MOZ_NO_REMOTE': '1', 03:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:51 INFO - 'NO_EM_RESTART': '1', 03:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:51 INFO - 'PWD': '/builds/slave/test', 03:09:51 INFO - 'SHELL': '/bin/bash', 03:09:51 INFO - 'SHLVL': '1', 03:09:51 INFO - 'TERM': 'linux', 03:09:51 INFO - 'TMOUT': '86400', 03:09:51 INFO - 'USER': 'cltbld', 03:09:51 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:51 INFO - '_': '/tools/buildbot/bin/python'} 03:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:51 INFO - Downloading/unpacking jsonschema 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:53 INFO - Downloading/unpacking functools32 (from jsonschema) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:54 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:54 INFO - warning: no files found matching '*.txt' 03:09:54 INFO - no previously-included directories found matching 'build' 03:09:54 INFO - no previously-included directories found matching 'dist' 03:09:54 INFO - no previously-included directories found matching '.git*' 03:09:54 INFO - Installing collected packages: jsonschema, functools32 03:09:54 INFO - Running setup.py install for functools32 03:09:54 INFO - warning: no files found matching '*.txt' 03:09:54 INFO - no previously-included directories found matching 'build' 03:09:54 INFO - no previously-included directories found matching 'dist' 03:09:54 INFO - no previously-included directories found matching '.git*' 03:09:54 INFO - Successfully installed jsonschema functools32 03:09:54 INFO - Cleaning up... 03:09:54 INFO - Return code: 0 03:09:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdba438cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ec1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', '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 03:09:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:09:54 INFO - Using env: {'DISPLAY': ':0', 03:09:54 INFO - 'HOME': '/home/cltbld', 03:09:54 INFO - 'LANG': 'en_US.UTF-8', 03:09:54 INFO - 'LANGUAGE': 'en_US:en', 03:09:54 INFO - 'LOGNAME': 'cltbld', 03:09:54 INFO - 'MAIL': '/var/mail/cltbld', 03:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:54 INFO - 'MOZ_NO_REMOTE': '1', 03:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:54 INFO - 'NO_EM_RESTART': '1', 03:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:54 INFO - 'PWD': '/builds/slave/test', 03:09:54 INFO - 'SHELL': '/bin/bash', 03:09:54 INFO - 'SHLVL': '1', 03:09:54 INFO - 'TERM': 'linux', 03:09:54 INFO - 'TMOUT': '86400', 03:09:54 INFO - 'USER': 'cltbld', 03:09:54 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:54 INFO - '_': '/tools/buildbot/bin/python'} 03:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:54 INFO - Downloading/unpacking flake8 03:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:09:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:09:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:09:57 INFO - no previously-included directories found matching 'docs/_build' 03:09:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:09:57 INFO - Running setup.py install for pep8 03:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:09:57 INFO - no previously-included directories found matching 'docs/_build' 03:09:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:09:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:09:57 INFO - Cleaning up... 03:09:57 INFO - Return code: 0 03:09:57 INFO - Running post-action listener: _resource_record_post_action 03:09:57 INFO - Running post-action listener: _start_resource_monitoring 03:09:57 INFO - Starting resource monitoring. 03:09:57 INFO - ##### 03:09:57 INFO - ##### Finished create-virtualenv step (success) 03:09:57 INFO - ##### 03:09:57 INFO - ##### 03:09:57 INFO - ##### Running install step. 03:09:57 INFO - ##### 03:09:57 INFO - Running pre-action listener: _resource_record_pre_action 03:09:57 INFO - Running main action method: install 03:09:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:57 INFO - Reading from file tmpfile_stdout 03:09:57 INFO - Detecting whether we're running mozinstall >=1.0... 03:09:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:09:57 INFO - Reading from file tmpfile_stdout 03:09:57 INFO - Output received: 03:09:57 INFO - Usage: mozinstall [options] installer 03:09:57 INFO - Options: 03:09:57 INFO - -h, --help show this help message and exit 03:09:57 INFO - -d DEST, --destination=DEST 03:09:57 INFO - Directory to install application into. [default: 03:09:57 INFO - "/builds/slave/test"] 03:09:57 INFO - --app=APP Application being installed. [default: firefox] 03:09:57 INFO - mkdir: /builds/slave/test/build/application 03:09:57 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'] 03:09:57 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 03:10:14 INFO - Reading from file tmpfile_stdout 03:10:14 INFO - Output received: 03:10:14 INFO - /builds/slave/test/build/application/firefox/firefox 03:10:14 INFO - Running post-action listener: _resource_record_post_action 03:10:14 INFO - ##### 03:10:14 INFO - ##### Finished install step (success) 03:10:14 INFO - ##### 03:10:14 INFO - ##### 03:10:14 INFO - ##### Running run-tests step. 03:10:14 INFO - ##### 03:10:14 INFO - Running pre-action listener: _resource_record_pre_action 03:10:14 INFO - Running main action method: run_tests 03:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:10:14 INFO - Python 2.7.3 03:10:14 INFO - Return code: 0 03:10:14 INFO - grabbing minidump binary from tooltool 03:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:10:14 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 0x1f509c0>, '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 0x1f518c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f51d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:10:14 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 03:10:14 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 03:10:14 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 03:10:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:10:14 INFO - Return code: 0 03:10:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:10:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:10:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:10:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:10:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:10:15 INFO - Return code: 0 03:10:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:10:15 INFO - Using env: {'DISPLAY': ':0', 03:10:15 INFO - 'HOME': '/home/cltbld', 03:10:15 INFO - 'LANG': 'en_US.UTF-8', 03:10:15 INFO - 'LANGUAGE': 'en_US:en', 03:10:15 INFO - 'LOGNAME': 'cltbld', 03:10:15 INFO - 'MAIL': '/var/mail/cltbld', 03:10:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:10:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:15 INFO - 'MOZ_NO_REMOTE': '1', 03:10:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:10:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:10:15 INFO - 'NO_EM_RESTART': '1', 03:10:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:15 INFO - 'PWD': '/builds/slave/test', 03:10:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:10:15 INFO - 'SHELL': '/bin/bash', 03:10:15 INFO - 'SHLVL': '1', 03:10:15 INFO - 'TERM': 'linux', 03:10:15 INFO - 'TMOUT': '86400', 03:10:15 INFO - 'USER': 'cltbld', 03:10:15 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513', 03:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:15 INFO - '_': '/tools/buildbot/bin/python'} 03:10:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:10:15 INFO - mozversion application_buildid: 20160525001739 03:10:15 INFO - mozversion application_changeset: 97b22d33cd789520910ef8dfc81374ab53c62134 03:10:15 INFO - mozversion application_display_name: Nightly 03:10:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:10:15 INFO - mozversion application_name: Firefox 03:10:15 INFO - mozversion application_remotingname: firefox 03:10:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:10:15 INFO - mozversion application_vendor: Mozilla 03:10:15 INFO - mozversion application_version: 49.0a1 03:10:15 INFO - mozversion platform_buildid: 20160525001739 03:10:15 INFO - mozversion platform_changeset: 97b22d33cd789520910ef8dfc81374ab53c62134 03:10:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 03:10:15 INFO - mozversion platform_version: 49.0a1 03:10:15 INFO - using testdate: 1464171015 03:10:15 INFO - actual date: 1464171015 03:10:15 INFO - starting webserver on 'localhost:47145' 03:10:15 INFO - SUITE-START | Running 2 tests 03:10:15 INFO - TEST-START | dromaeo_css 03:10:15 INFO - operating with platform_type : linux_ 03:10:15 INFO - Initialising browser for dromaeo_css test... 03:10:15 INFO - TEST-INFO | started process 13835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXEmK3v/profile http://localhost:47145/getInfo.html) 03:10:19 INFO - PROCESS | 13835 | __metrics Screen width/height:1600/1200 03:10:19 INFO - PROCESS | 13835 | colorDepth:24 03:10:19 INFO - PROCESS | 13835 | Browser inner width/height: 1024/697 03:10:19 INFO - PROCESS | 13835 | __metrics 03:10:20 ERROR - PROCESS | 13835 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:10:21 INFO - TEST-INFO | 13835: exit 0 03:10:21 INFO - Browser initialized. 03:10:21 INFO - Running cycle 1/1 for dromaeo_css test... 03:10:21 INFO - TEST-INFO | started process 13918 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXEmK3v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:33 INFO - PROCESS | 13918 | RSS: Main: 154759168 03:10:33 INFO - PROCESS | 13918 | 03:11:24 INFO - PROCESS | 13918 | 1464171084129 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] 03:13:32 INFO - PROCESS | 13918 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47145/tests/dromaeo/cssquery-ext.html) 03:13:33 INFO - PROCESS | 13918 | RSS: Main: 259309568 03:13:33 INFO - PROCESS | 13918 | 03:16:35 INFO - PROCESS | 13918 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-ext.html (next: http://localhost:47145/tests/dromaeo/cssquery-jquery.html) 03:16:36 INFO - PROCESS | 13918 | RSS: Main: 242724864 03:16:36 INFO - PROCESS | 13918 | 03:19:38 INFO - PROCESS | 13918 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47145/tests/dromaeo/cssquery-mootools.html) 03:19:39 INFO - PROCESS | 13918 | RSS: Main: 242745344 03:19:39 INFO - PROCESS | 13918 | 03:22:41 INFO - PROCESS | 13918 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47145/tests/dromaeo/cssquery-prototype.html) 03:22:41 INFO - PROCESS | 13918 | RSS: Main: 241274880 03:22:41 INFO - PROCESS | 13918 | 03:25:44 INFO - PROCESS | 13918 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47145/tests/dromaeo/cssquery-yui.html) 03:25:45 INFO - PROCESS | 13918 | RSS: Main: 202543104 03:25:45 INFO - PROCESS | 13918 | 03:28:47 INFO - PROCESS | 13918 | Cycle 1(1): loaded http://localhost:47145/tests/dromaeo/cssquery-yui.html (next: http://localhost:47145/tests/dromaeo/cssquery-dojo.html) 03:28:48 INFO - PROCESS | 13918 | RSS: Main: 272154624 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | __start_tp_report 03:28:48 INFO - PROCESS | 13918 | _x_x_mozilla_page_load 03:28:48 INFO - PROCESS | 13918 | _x_x_mozilla_page_load_details 03:28:48 INFO - PROCESS | 13918 | |i|pagename|runs| 03:28:48 INFO - PROCESS | 13918 | |0;dojo.html;3112.2219445138717;3118.610173728284;3142.5443310719947;3153.2274592724784;3178.570892929455;5310.70791106489;5313.814016509423;5323.60072994525;5404.675719456831;5416.783328666858;5411.783528658859;5413.675179489233;5417.485338892809;5445.945540544593;5469.343678758544;4853.417589889218;4947.727874966876;4948.901021979558;4952.826651067214;5079.492050794928;4993.0263598598285;4993.6254780891395;5033.295338652591;5063.063333283354;5064.848054558369;4842.854714358575;4879.878003049899;4891.755412229374;4963.6277279204205;4964.87587810304;4944.505549445063;4948.059868624986;4949.752512374367;4951.183054795954;4960.677555958887;4834.033193361342;4872.902541949141;4875.219964805649;4886.926696099561;4895.828645997409;5290.444503327172;5352.759125839308;5360.544353729938;5427.864303392407;5432.972835135799;5854.18041474194;5871.79448719297;5873.089671100944;5874.5006674432725;5880.852978675567;5716.256886604757;5718.456746609066;5762.164486149527;5763.337216220147;5823.068309070529;5386.407495175527;5425.213344065089;5433.1850222466555;5446.863828404282;5451.563874889999;5810.622309549865;5948.226730525004;5961.5230781537375;5962.433568810956;5963.701814909324;5618.634788738719;5664.5751568631695;5680.801171959044;5752.050911599539;5760.2223699800825;5806.187133801272;5808.419158084158;5816.854578635568;5843.503302219359;5857.121431785266;5590.888182236333;5637.7181140942785;5785.508231800344;5790.160426738099;5790.565707571949;5764.337101233328;5805.8548536286935;5822.33043200029;5834.474897259267;5837.182794408786;4435.955640443619;4438.289873620205;4447.644188464915;4452.955470445254;4491;3897.376419772823;3918.8628398005935;3944.15200731844;3995.34076877312;4015.31739604268;5453.100238460689;5507.807226747044;5509.449055094458;5509.889802204014;5559.416261292587;5818.44724751148;5832.387599302097;5835.387284335084;5837.357890632027;5895.469407753323;5330.92003619946;5439.646422982493;5440.537554307928;5483.067878460672;5499.010178167888;5018.447970723218;5033.949660503421;5040.823571175065;5042.873928151752;5075.56857667095;6013.9699301503215;6052.788152414732;6061.939380606225;6067.696615169224;6069.848253793602;5697.373288938296;5780.797672081457;5798.043322851682;5864.413558644272;5906.40935906406;6078.6048906820915;6084.330723620486;6085.543584231289;6119.571629986036;6139.048447490646;5722.828315150552;5733.140028995684;5734.483896449339;5735.13972904051;5739.598228123991;5694.886102278014;5731.484166425042;5738.340090889601;5850.502707269759;5853.824385268449;5637.605367624391;5669.829905102853;5676;5686.34607020198;5711;5672.035753921926;5682.829515114553;5682.971585142213;5683.261176047087;5685.403032681675;5461.590380721541;5498.917516237339;5501.944980550304;5545.1682247663175;5579.944200557942;5690.829275121753;5692.345380281321;5728.770849165987;5729.828105156852;5729.914051289151;5495.862603434946;5561.138023606348;5562.72186390687;5568.916466252928;5609 03:28:48 INFO - PROCESS | 13918 | |1;ext.html;598.2551723104732;634.1787385335987;643.189581127779;649.0815496073056;660.6201434175348;44521.55478445214;45879.541204587986;45948.08103837921;45986.770066149664;49014.26478602815;46935;47720.761396193055;47847.76076119623;47903.52096479034;47945;8896.199342059213;9081.909180908173;9084.091590840897;9084.318676099287;9084.818303633923;8922.86615700765;9013.909860901405;9030;9033.277337812991;9033.819323613556;9075.63697452101;9121.908780912205;9131.543422828832;9139.908600914005;9142.582899650532;9023.503707296097;9089.545522723838;9096.8635470468;9098.090190980849;9100.408473449204;8862.069482704288;8940.64237430502;8956.462612243222;8956.820863582692;8960.372773905829;42821.57178428238;43175.56824431779;43327;43334.34998475025;43334.56665433337;3498.3702933471964;3650.6166852480637;3696.353138200831;3919.627635374635;4151.398047283157;33139.50290745641;33294.6670533294;33601.49597756036;33623.159421014425;35245.47131793025;4232.724872883253;4296.2481565725875;4321.842286124082;4352.738835669838;4406.845760398371;340694.9999999975;341061;343055;343713;345974;61734;61903;62110;62116.37883621106;62121.37878621247;63952;64204.357956420776;64205.678971604844;64228.39429014312;64252;17220.913895430444;17503.73744393835;17517.299308027792;17520.64958700819;17526.123693815258;13083.738325233633;13160.60518184456;13184;13228;13228.07403481747;15983.871384631315;18356.908215458836;18638.72041919345;18785.7182142268;19057.523561911064;5325.547328477122;6100.359462256488;6456.225252969673;6456.451201647913;6488.7728929488185;991.2367477042781;1013.8428543575758;1049.7480604654982;1060.7878424315168;1065.7158124460007;30722.67604793961;32612.67387326144;32628.205448700075;32790.344193116005;33330.66669333324;1021.8518314844306;1093.9179561532758;1126.6732647532288;1141.9714507137387;1158.5481662151767;16508.25712842925;17074.82925170757;17101.657966840594;17203.397881074103;17422.303107875796;540237;540877;542931;543548;547052;59766.70116649476;59914.70042649846;60013.699931500065;60075.39924600785;60227.39772602305;1242.2236102435977;1350.6218258887527;1366.480737319812;1388.0561218371604;1408.1199250468458;32614.51078233781;33802.14088225171;34125.31749365049;34167.487487687205;34323.14192145217;46215.76892115518;51517.742411287705;52093.21860172176;52137.217941730145;52153;408577;410070;412594;412818;413942;25406.364840878385;27218.183454496397;28241.43517129605;28352.716472835833;28685;49228;49570.752146239036;49811.25283120684;50191;50218.24672629841;41363.379549306184;41743.37384939289;42134.78932605317;42911.78544107259;43445.56554434415;41045;41557.16885662209;41878.79060604677;41931.58068419277;42422.36366454567;42003.36994945017;42815;43163.97852150445;43234.13531729411;43255;35638.28723425569;36628.633713663585;36801.63198367982;37711.24577508489;37878.2424351517;28420.43159136847;28683;28747.28131796722;28820;28834.42331153323 03:28:48 INFO - PROCESS | 13918 | |2;jquery.html;386.1851493349034;386.29693956998256;394.34144977886933;397.71563332217494;408.397613520058;17947.10264486777;17962.91018544907;20088.4977875553;20128.899355503203;20137.798622013775;12191.512339506431;12370.50517979282;12387.318697471635;12401.13192076555;17244.396446124356;5058.772355244023;5204.609654275925;5241.633085684004;5243.161094224924;5248.685078895279;3605.8197090145386;5549.556035517149;5869.383714709958;5878.647281163123;5922.230110085704;5825.679587622679;5917.8816423671715;5921.052631578948;5921.792737254197;5944.851378715523;5794.768209271624;5895.233619629464;5914.585978981473;5929.525637948976;5929.703514824263;3481.3037392521596;3737.9439308410397;3740.233252183296;5236.633435659505;5646.576506761969;13718.65703357414;13813.378397972016;13825.308734563232;20365.898170509052;21403.464913377135;2307.227078928544;2311.433698743802;2359.3629719975684;2411.5418070566534;2437.5490534251044;9378.155965963097;9601.90398096024;9682.724559728458;9768.218542516654;9789.510524473819;1714.3057061889954;1872.7565416495904;1888.1314595286185;1903.8767127394904;1921.6637088509594;76909.61545192295;78865;79637;80355;80583.5970820148;8980.640774369083;9504.334696571173;9802.509874506246;10036.243657359933;10142.18862490999;12795.232286062866;12884.355782210852;12894.097413181174;12904.29026403538;12927.418266178045;7734.574598397142;7764.767056988299;7907.881381779278;8050.476719013245;8060.274575288252;8061.919380806235;8069.878951815727;8126.065502467174;8194.918050819415;8247.670093196324;3940.862069827542;3985.4021896715726;4009.69927255457;4046.4537287466405;4169.60388763067;1834.2316850820696;1836.724491326285;2040.1839264294103;2061.2373421833718;2077.9064942077644;891.4294851295176;959.3332633819429;962.7785609309898;1044.2690116918188;1047.984280235797;18712.812871871378;18923.148458319134;18973.241070357308;19285.4214373569;19530;970.6505657963128;1000.3347773730533;1023.181454836143;1042.6298663974333;1045.1168762395764;10451;10890.074343680875;10954.945225273823;11008.559657613605;11027.669169924915;491319;496350;496765;506989;509916;88208.5589572048;90335.54832225796;90550;91391.54304228436;91536.08463915408;964.4117088575963;992.2905122837207;994.4878387630521;1006.5671761142632;1009.0666133826181;16580.0880951544;16750.66498670044;16994.49016529506;17038.57403564921;17222.91388543049;38821.41767873541;39630.80184599059;40958;42015.15969680528;42193;55193.724031379585;57340.42659573351;57540.08240963388;58214.708926455096;58539.70730146322;7347.08161479815;7547.358474529727;8256.380771442286;8348.749537513884;8406.495610263404;12792.74414511723;12917.870821292043;13061.673458163621;13286.867131328563;13431.261280629658;11666.824997624874;11776.764464710486;11783.469743861719;12194.87805121973;12228.266304021787;11573.074154067823;11593.18847680688;11669.59156429521;11693.415329233334;11947.402629868646;11374.374409407563;11841.171118021655;11898.881011189776;12097.334646594521;12434.502619895104;7779.18318576564;7850.332721718718;7893.486923349964;8074.798130046795;8233.423660343718;10108.292419530857;10895.182861285277;10953.890461095605;11115.277506962346;11338.94330528375 03:28:48 INFO - PROCESS | 13918 | |3;mootools.html;1295.9416826242818;1479.711456266028;1504.8269449013367;1684.7220208665572;1841.5212044868338;8210.302124319433;8592.01191862936;8597.570121493924;8814.86777698335;8824.647014119428;8516.489010659348;8746.86879696805;8769.649214031431;8785.428947118433;8797.560121993907;2001.1095062697107;2073.626747185506;2077.2937201351538;2377.6433534969765;2401.2916189724074;2314.618088015484;2375.0737212487143;2383.094424118832;2387.462820865309;2393.8563686178886;2224.443889027743;2241.428435748882;2243.876586787726;2253.9323820285335;2306.192832508617;2002.6695595226915;2274.442761523421;2368.052778888441;2397.0531640002187;2399.436132508859;2355.2580937004786;2381.84518006329;2390.2829151254555;2392.629142482918;2406.290144407404;8284.46151000183;8340.791480212982;8367.748967530984;8367.874481882776;8368.748937531884;5159.380874295071;5278.9208161877605;5284.365876094855;5291.497307755757;5302.814401495968;3144.308252184516;3164.27221738999;3289.2105894585366;3319.452290372086;3324.9168770780684;1104.1222228328497;1115.7935781880378;1128.136975213962;1151.2171723228203;1162.4071723421116;11754.764904701859;11944.283342999448;11982.760344793056;11983.820242696367;12037.398130093548;4599.678022538457;4760.571548560577;4821;4821.566059054632;4827.589654879374;10193.898061019296;10219.335743176669;10222.539985700661;10253.077223049811;10260.230482713827;5360.65155764874;5365.034293827149;5366.221897824794;5369.5972802040005;5381.48875856793;4321.157374312042;4680.648951328664;4724.173269677793;4753.691010084333;4847.369841920598;3969.4839670843185;4022.5374081980362;4075.4294398784195;4098.405731168964;4150.5641907599875;1285.5436320106414;1329.1227789658972;1329.3154025676783;1351.3108314759543;1355.159800923414;880.4673172730534;967.0909345215475;971.523953262896;990.0841721407813;991.1624677147798;5898.4101589840675;6017.398260173947;6108.847278818065;6115.205023346936;6133.1413602095745;424.2554317173341;424.2829617945662;425.5255390239922;427.80534856640156;446.35055993529335;4135;4151.564085771011;4235.512916014698;4285.97857010713;4296.570342965679;10025.949870250748;10120.544575494121;10352.171826254033;10601.57593696259;10606.946965265124;10630;10671.039606435457;10744.086752626114;10795.29830561011;10814.945925270324;7880.842383152305;8232.094469608219;8238.464499807613;8245.546494942835;8246.422750407413;5796.101604251347;5956.315023772322;6008.188894499187;6080.969595151996;6118.938810611837;9550.283728720513;9582.520873956413;9673.37123086997;9692.757681057748;9724.173445257233;9705.514724263618;9890.258230632588;9902.851457228291;9939.602415903282;9979.800403992025;6956.339147780953;7197.316254955771;7356.264373562601;7717.421193410629;7833.5691536966015;8207.466514676527;8209.138040505899;8260.215279548433;8287.12985136576;8290.046644635944;7290.3438690518105;7296.087989001375;7498.775036748906;7562.357199638092;7598.316151546387;7103.644817759195;7194.640267986475;7276.854462910818;7313.085864266966;7404.259574042768;7060.258672839484;7144.214136444883;7144.4641651877355;7149.14210294768;7156;5064.607233010952;5225.895482090261;5262.394824595068;5285.075111855487;5289.523942845162;5208.635395522277;5271.103912334989;5287.048331300402;5313.468653134656;5385.8114965976 03:28:48 INFO - PROCESS | 13918 | |4;prototype.html;315.4337963355776;316.91601725542733;322.4244723169144;324.40147927074537;326.2577635878378;5287.92068118978;5314.282571852798;5343.919841202385;5356.196570514425;5391.56867450604;5228.4771522847695;5253.290805741224;5262.000219958205;5274.520975141677;5301.6818990860575;4084.3254265751048;4184.121334519755;4189.664826813848;4212.810423530948;4220.97889510552;4150.5719154294775;4155.979220103895;4158.773161917242;4162.125953549758;4180;4067.227226826908;4115.176964607075;4129.855455059073;4139.337705967046;4147.771872547009;3876.6704830089193;3943.7239393242485;4102.958970410287;4104.774237416941;4111.71218014739;3574.891783547109;3606.0263728793343;3608.6752192302556;3629.709623230135;3631.8547258109643;5065.873353166164;5090.694558326475;5093.516115968977;5097.3118628984985;5131.358580177478;4424.292113261895;4521.208788462039;4626.236670949323;4631.791569379352;4683.063387322515;5458.153986132156;5461.617686761928;5463.562914966793;5463.945360546383;5471.890562188734;3874.6900247980375;3897.5907529709257;3898.8050597470015;3922.5489068757174;3946.8223929922933;5478.780848766045;5726.0838265877255;5745.511631511368;5793.102069179284;5811.2566230131215;4013.458183145238;4015.759054456742;4019.115794525258;4031.838726450968;4040.6463834615734;4040.777757223322;4044.5753195914594;4063.8577649782114;4073.2260870434525;4082.4692789937712;4436.511983681792;4648.604868586208;4652.32541281519;4686.5782079613;4722.882897287774;5503.257060296889;5531.340597820637;5542.806001789999;5549.472800083986;5568.49883510486;1367.8290213723285;1381.88944884409;1385.0263374691026;1393.6724869655623;1394.5607133752835;851.7529916324197;889.5285498685707;893.9374243802871;904.2810965282616;912.2109375390372;4760.309755085494;4774.355462012653;4789.87437952081;4809.663323567387;4818.590419814285;5045.621578381636;5077.898442031139;5124.359455068116;5135.922961155586;5176.922346164811;4650.325702773079;4771.308160316804;4803.639727020418;4849.2968519564465;4882.7558622068755;2922.8392438415667;2946.703450481781;3020.758339332848;3026.6973302669558;3034.908952731422;5764.654120752768;5799.130130480462;5817.563682723812;5833.329167145747;5849.61977471463;5373.919391209135;5665.685573939291;5723.998300297515;5770.653760774367;5824.291704357494;5801.448862358069;5857.096443655914;5869.413058694096;5895.55783316253;5939.732712027969;5422.186671999231;5636.182753500638;5648.971755141198;5677.3754886963225;5702.657840529581;5616.297962754656;5622.606417550732;5656.218291238411;5657.858553536194;5665.716714164358;5807.8548036299435;5811.476967072984;5819.418058194146;5824.767009319579;5851.678157701367;3801.4867992820614;3952.9407058894717;4005.7796821175116;4014.437978682928;4026.67786577079;5670.554008727748;5682.403347648437;5718.857028574319;5739.282589676291;5817.505512031525;5583.330000399978;5592.524635405874;5597.524210442158;5598;5599.244102046172;5349.705766182898;5467.414449809511;5530.059889818821;5546.528545073714;5558.527525160407;5452.236686863763;5454.4545545445135;5465.945340546544;5471.781128754806;5486.31421072366;5443.0607471964695;5531.668099914018;5551.945780061403;5564.721763911707;5587.636803607753;5417.295751552273;5441.619086663895;5477.698726569917;5488.533474654699;5496.835094947158 03:28:48 INFO - PROCESS | 13918 | |5;yui.html;728.8906664000399;814.8370325934815;823.5964377455045;849.1084361420507;859.4542465534383;13788.172709637429;14136.787948180772;15170.924145379271;15356.6928661427;15393;13333.466661333536;13337.866621333784;13342.399592018359;13414.597562073153;13464.192148471098;668.802703202554;755.8563872864165;791.1139523733426;793.4683761879554;805.3758337288618;796.9362451003906;799.4643588795522;802.6107337941094;804.1194891593696;804.9396295277849;848.8005318750089;850.8128211793396;850.9958249265894;853.7908212487918;856.2122846980793;698.078536332042;724.1925253342538;724.463896716426;817.8609636361773;840.7141571865565;620.8106527509108;623.7847942459844;623.8783437229743;624.2883113250898;624.7688355308519;12166.391680415943;12174;12184.573539926148;12186.451609677586;12207.145499815017;13637.863621363758;13739.725205495834;13824.377902994289;13852.584422467342;13978.790318145237;12771.233725976472;12804.359782010955;12824.487020519167;12852;12985.285809280485;4446.221911165566;4468.374427580141;4529.818807247706;4696.412948381452;4728.385309909724;62058.68970655163;62263.37736622621;63161.68419157921;63839.999999999534;63887;11580.594679186357;11644.475998580085;11690.590829320932;11707.590234341927;12152.5138994441;19641.41075767729;19641.901790491243;19642.919639419968;19644.70532942007;19662.11520481582;8112.553809540414;8177.223163799429;8177.509349439053;8226.670933162606;8448.535330556879;4631.976840115778;4646.163690535668;4719.38647975761;4726.574608285271;4790.089882922233;3387.3733359328603;3493.039414161126;3517.4547945068557;3518.964810351915;3519.63043880394;639.0669622351326;645.4868379638199;650.4568685147955;660.0594153331483;671.70445004198;605.0772571827997;652.885744994624;665.3679004945321;670.7015378156674;684.1550684904139;8649.308055355556;8746.387752857367;8786.912130878738;8846.071162527971;8931.788803322079;540.2949151357516;650.5381179362611;672.2067959807474;679.5277282288861;697.6790676288863;4188.916221675549;4321.092570560218;4377.671674624416;4389.670774691911;4479.910401791946;70094;71559.64220178865;73174.63412682903;73418.26581734115;73510.26489735142;60765.39234607686;61196;61878.690606546676;62251;64105.35894641087;56103.71948140233;56271.437285627435;56581.71709141428;57895.71052144797;58388.99999999915;8267.751967440987;8388.119247478928;8402.7011168461;8425.95787021061;8602.39783215169;34171.31657366888;34892;34919.82540087385;36186.45720314145;38616.22767544577;49580.50419495759;49913.50086499234;50219.24232651986;51021.23468148055;51176.48823511866;12720.618381448572;12732.681682958;12839.486420543073;13046.217226966412;13113.378163687443;11832.349220792596;11849.407529623311;12104.51581936748;12858.614241572393;13404.32978351098;11467.483963221508;11720;11724.648260552198;11746.70633234176;11814.645560633195;11462.197646164688;11677.824832627348;11830.101201455569;11833.704157396134;12384.75230495403;11710.473028713557;12042.096842736655;12087.51649934028;12180.147389682637;12205.084618653815;9955.900440995787;10079.24405669563;10087.899121008695;10253.384796912209;10283.845742313722;10000.899990999997;10116.797664046826;10292.536835842255;10342;10344.793104137725 03:28:48 INFO - PROCESS | 13918 | __end_tp_report 03:28:48 INFO - PROCESS | 13918 | __start_cc_report 03:28:48 INFO - PROCESS | 13918 | _x_x_mozilla_cycle_collect,948 03:28:48 INFO - PROCESS | 13918 | __end_cc_report 03:28:48 INFO - PROCESS | 13918 | __startTimestamp1464172128108__endTimestamp 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | ------- Summary: start ------- 03:28:48 INFO - PROCESS | 13918 | Number of tests: 6 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | [#0] dojo.html Cycles:165 Average:5391.42 Median:5568.92 stddev:613.38 (11.0%) stddev-sans-first:588.62 03:28:48 INFO - PROCESS | 13918 | Values: 3112.2 3118.6 3142.5 3153.2 3178.6 5310.7 5313.8 5323.6 5404.7 5416.8 5411.8 5413.7 5417.5 5445.9 5469.3 4853.4 4947.7 4948.9 4952.8 5079.5 4993.0 4993.6 5033.3 5063.1 5064.8 4842.9 4879.9 4891.8 4963.6 4964.9 4944.5 4948.1 4949.8 4951.2 4960.7 4834.0 4872.9 4875.2 4886.9 4895.8 5290.4 5352.8 5360.5 5427.9 5433.0 5854.2 5871.8 5873.1 5874.5 5880.9 5716.3 5718.5 5762.2 5763.3 5823.1 5386.4 5425.2 5433.2 5446.9 5451.6 5810.6 5948.2 5961.5 5962.4 5963.7 5618.6 5664.6 5680.8 5752.1 5760.2 5806.2 5808.4 5816.9 5843.5 5857.1 5590.9 5637.7 5785.5 5790.2 5790.6 5764.3 5805.9 5822.3 5834.5 5837.2 4436.0 4438.3 4447.6 4453.0 4491.0 3897.4 3918.9 3944.2 3995.3 4015.3 5453.1 5507.8 5509.4 5509.9 5559.4 5818.4 5832.4 5835.4 5837.4 5895.5 5330.9 5439.6 5440.5 5483.1 5499.0 5018.4 5033.9 5040.8 5042.9 5075.6 6014.0 6052.8 6061.9 6067.7 6069.8 5697.4 5780.8 5798.0 5864.4 5906.4 6078.6 6084.3 6085.5 6119.6 6139.0 5722.8 5733.1 5734.5 5735.1 5739.6 5694.9 5731.5 5738.3 5850.5 5853.8 5637.6 5669.8 5676.0 5686.3 5711.0 5672.0 5682.8 5683.0 5683.3 5685.4 5461.6 5498.9 5501.9 5545.2 5579.9 5690.8 5692.3 5728.8 5729.8 5729.9 5495.9 5561.1 5562.7 5568.9 5609.0 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | [#1] ext.html Cycles:180 Average:60306.01 Median:42866.68 stddev:116635.25 (272.1%) stddev-sans-first:116876.29 03:28:48 INFO - PROCESS | 13918 | Values: 598.3 634.2 643.2 649.1 660.6 44521.6 45879.5 45948.1 45986.8 49014.3 46935.0 47720.8 47847.8 47903.5 47945.0 8896.2 9081.9 9084.1 9084.3 9084.8 8922.9 9013.9 9030.0 9033.3 9033.8 9075.6 9121.9 9131.5 9139.9 9142.6 9023.5 9089.5 9096.9 9098.1 9100.4 8862.1 8940.6 8956.5 8956.8 8960.4 42821.6 43175.6 43327.0 43334.3 43334.6 3498.4 3650.6 3696.4 3919.6 4151.4 33139.5 33294.7 33601.5 33623.2 35245.5 4232.7 4296.2 4321.8 4352.7 4406.8 340695.0 341061.0 343055.0 343713.0 345974.0 61734.0 61903.0 62110.0 62116.4 62121.4 63952.0 64204.4 64205.7 64228.4 64252.0 17220.9 17503.7 17517.3 17520.6 17526.1 13083.7 13160.6 13184.0 13228.0 13228.1 15983.9 18356.9 18638.7 18785.7 19057.5 5325.5 6100.4 6456.2 6456.5 6488.8 991.2 1013.8 1049.7 1060.8 1065.7 30722.7 32612.7 32628.2 32790.3 33330.7 1021.9 1093.9 1126.7 1142.0 1158.5 16508.3 17074.8 17101.7 17203.4 17422.3 540237.0 540877.0 542931.0 543548.0 547052.0 59766.7 59914.7 60013.7 60075.4 60227.4 1242.2 1350.6 1366.5 1388.1 1408.1 32614.5 33802.1 34125.3 34167.5 34323.1 46215.8 51517.7 52093.2 52137.2 52153.0 408577.0 410070.0 412594.0 412818.0 413942.0 25406.4 27218.2 28241.4 28352.7 28685.0 49228.0 49570.8 49811.3 50191.0 50218.2 41363.4 41743.4 42134.8 42911.8 43445.6 41045.0 41557.2 41878.8 41931.6 42422.4 42003.4 42815.0 43164.0 43234.1 43255.0 35638.3 36628.6 36801.6 37711.2 37878.2 28420.4 28683.0 28747.3 28820.0 28834.4 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | [#2] jquery.html Cycles:180 Average:28560.72 Median:19603.86 stddev:82467.77 (420.7%) stddev-sans-first:82671.98 03:28:48 INFO - PROCESS | 13918 | Values: 386.2 386.3 394.3 397.7 408.4 17947.1 17962.9 20088.5 20128.9 20137.8 12191.5 12370.5 12387.3 12401.1 17244.4 5058.8 5204.6 5241.6 5243.2 5248.7 3605.8 5549.6 5869.4 5878.6 5922.2 5825.7 5917.9 5921.1 5921.8 5944.9 5794.8 5895.2 5914.6 5929.5 5929.7 3481.3 3737.9 3740.2 5236.6 5646.6 13718.7 13813.4 13825.3 20365.9 21403.5 2307.2 2311.4 2359.4 2411.5 2437.5 9378.2 9601.9 9682.7 9768.2 9789.5 1714.3 1872.8 1888.1 1903.9 1921.7 76909.6 78865.0 79637.0 80355.0 80583.6 8980.6 9504.3 9802.5 10036.2 10142.2 12795.2 12884.4 12894.1 12904.3 12927.4 7734.6 7764.8 7907.9 8050.5 8060.3 8061.9 8069.9 8126.1 8194.9 8247.7 3940.9 3985.4 4009.7 4046.5 4169.6 1834.2 1836.7 2040.2 2061.2 2077.9 891.4 959.3 962.8 1044.3 1048.0 18712.8 18923.1 18973.2 19285.4 19530.0 970.7 1000.3 1023.2 1042.6 1045.1 10451.0 10890.1 10954.9 11008.6 11027.7 491319.0 496350.0 496765.0 506989.0 509916.0 88208.6 90335.5 90550.0 91391.5 91536.1 964.4 992.3 994.5 1006.6 1009.1 16580.1 16750.7 16994.5 17038.6 17222.9 38821.4 39630.8 40958.0 42015.2 42193.0 55193.7 57340.4 57540.1 58214.7 58539.7 7347.1 7547.4 8256.4 8348.7 8406.5 12792.7 12917.9 13061.7 13286.9 13431.3 11666.8 11776.8 11783.5 12194.9 12228.3 11573.1 11593.2 11669.6 11693.4 11947.4 11374.4 11841.2 11898.9 12097.3 12434.5 7779.2 7850.3 7893.5 8074.8 8233.4 10108.3 10895.2 10953.9 11115.3 11338.9 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | [#3] mootools.html Cycles:180 Average:5699.39 Median:5111.99 stddev:3199.66 (62.6%) stddev-sans-first:3191.52 03:28:48 INFO - PROCESS | 13918 | Values: 1295.9 1479.7 1504.8 1684.7 1841.5 8210.3 8592.0 8597.6 8814.9 8824.6 8516.5 8746.9 8769.6 8785.4 8797.6 2001.1 2073.6 2077.3 2377.6 2401.3 2314.6 2375.1 2383.1 2387.5 2393.9 2224.4 2241.4 2243.9 2253.9 2306.2 2002.7 2274.4 2368.1 2397.1 2399.4 2355.3 2381.8 2390.3 2392.6 2406.3 8284.5 8340.8 8367.7 8367.9 8368.7 5159.4 5278.9 5284.4 5291.5 5302.8 3144.3 3164.3 3289.2 3319.5 3324.9 1104.1 1115.8 1128.1 1151.2 1162.4 11754.8 11944.3 11982.8 11983.8 12037.4 4599.7 4760.6 4821.0 4821.6 4827.6 10193.9 10219.3 10222.5 10253.1 10260.2 5360.7 5365.0 5366.2 5369.6 5381.5 4321.2 4680.6 4724.2 4753.7 4847.4 3969.5 4022.5 4075.4 4098.4 4150.6 1285.5 1329.1 1329.3 1351.3 1355.2 880.5 967.1 971.5 990.1 991.2 5898.4 6017.4 6108.8 6115.2 6133.1 424.3 424.3 425.5 427.8 446.4 4135.0 4151.6 4235.5 4286.0 4296.6 10025.9 10120.5 10352.2 10601.6 10606.9 10630.0 10671.0 10744.1 10795.3 10814.9 7880.8 8232.1 8238.5 8245.5 8246.4 5796.1 5956.3 6008.2 6081.0 6118.9 9550.3 9582.5 9673.4 9692.8 9724.2 9705.5 9890.3 9902.9 9939.6 9979.8 6956.3 7197.3 7356.3 7717.4 7833.6 8207.5 8209.1 8260.2 8287.1 8290.0 7290.3 7296.1 7498.8 7562.4 7598.3 7103.6 7194.6 7276.9 7313.1 7404.3 7060.3 7144.2 7144.5 7149.1 7156.0 5064.6 5225.9 5262.4 5285.1 5289.5 5208.6 5271.1 5287.0 5313.5 5385.8 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | [#4] prototype.html Cycles:180 Average:4601.92 Median:5240.88 stddev:1360.35 (26.0%) stddev-sans-first:1325.58 03:28:48 INFO - PROCESS | 13918 | Values: 315.4 316.9 322.4 324.4 326.3 5287.9 5314.3 5343.9 5356.2 5391.6 5228.5 5253.3 5262.0 5274.5 5301.7 4084.3 4184.1 4189.7 4212.8 4221.0 4150.6 4156.0 4158.8 4162.1 4180.0 4067.2 4115.2 4129.9 4139.3 4147.8 3876.7 3943.7 4103.0 4104.8 4111.7 3574.9 3606.0 3608.7 3629.7 3631.9 5065.9 5090.7 5093.5 5097.3 5131.4 4424.3 4521.2 4626.2 4631.8 4683.1 5458.2 5461.6 5463.6 5463.9 5471.9 3874.7 3897.6 3898.8 3922.5 3946.8 5478.8 5726.1 5745.5 5793.1 5811.3 4013.5 4015.8 4019.1 4031.8 4040.6 4040.8 4044.6 4063.9 4073.2 4082.5 4436.5 4648.6 4652.3 4686.6 4722.9 5503.3 5531.3 5542.8 5549.5 5568.5 1367.8 1381.9 1385.0 1393.7 1394.6 851.8 889.5 893.9 904.3 912.2 4760.3 4774.4 4789.9 4809.7 4818.6 5045.6 5077.9 5124.4 5135.9 5176.9 4650.3 4771.3 4803.6 4849.3 4882.8 2922.8 2946.7 3020.8 3026.7 3034.9 5764.7 5799.1 5817.6 5833.3 5849.6 5373.9 5665.7 5724.0 5770.7 5824.3 5801.4 5857.1 5869.4 5895.6 5939.7 5422.2 5636.2 5649.0 5677.4 5702.7 5616.3 5622.6 5656.2 5657.9 5665.7 5807.9 5811.5 5819.4 5824.8 5851.7 3801.5 3952.9 4005.8 4014.4 4026.7 5670.6 5682.4 5718.9 5739.3 5817.5 5583.3 5592.5 5597.5 5598.0 5599.2 5349.7 5467.4 5530.1 5546.5 5558.5 5452.2 5454.5 5465.9 5471.8 5486.3 5443.1 5531.7 5551.9 5564.7 5587.6 5417.3 5441.6 5477.7 5488.5 5496.8 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - PROCESS | 13918 | [#5] yui.html Cycles:180 Average:15815.66 Median:4722.98 stddev:19635.34 (415.7%) stddev-sans-first:19657.74 03:28:48 INFO - PROCESS | 13918 | Values: 728.9 814.8 823.6 849.1 859.5 13788.2 14136.8 15170.9 15356.7 15393.0 13333.5 13337.9 13342.4 13414.6 13464.2 668.8 755.9 791.1 793.5 805.4 796.9 799.5 802.6 804.1 804.9 848.8 850.8 851.0 853.8 856.2 698.1 724.2 724.5 817.9 840.7 620.8 623.8 623.9 624.3 624.8 12166.4 12174.0 12184.6 12186.5 12207.1 13637.9 13739.7 13824.4 13852.6 13978.8 12771.2 12804.4 12824.5 12852.0 12985.3 4446.2 4468.4 4529.8 4696.4 4728.4 62058.7 62263.4 63161.7 63840.0 63887.0 11580.6 11644.5 11690.6 11707.6 12152.5 19641.4 19641.9 19642.9 19644.7 19662.1 8112.6 8177.2 8177.5 8226.7 8448.5 4632.0 4646.2 4719.4 4726.6 4790.1 3387.4 3493.0 3517.5 3519.0 3519.6 639.1 645.5 650.5 660.1 671.7 605.1 652.9 665.4 670.7 684.2 8649.3 8746.4 8786.9 8846.1 8931.8 540.3 650.5 672.2 679.5 697.7 4188.9 4321.1 4377.7 4389.7 4479.9 70094.0 71559.6 73174.6 73418.3 73510.3 60765.4 61196.0 61878.7 62251.0 64105.4 56103.7 56271.4 56581.7 57895.7 58389.0 8267.8 8388.1 8402.7 8426.0 8602.4 34171.3 34892.0 34919.8 36186.5 38616.2 49580.5 49913.5 50219.2 51021.2 51176.5 12720.6 12732.7 12839.5 13046.2 13113.4 11832.3 11849.4 12104.5 12858.6 13404.3 11467.5 11720.0 11724.6 11746.7 11814.6 11462.2 11677.8 11830.1 11833.7 12384.8 11710.5 12042.1 12087.5 12180.1 12205.1 9955.9 10079.2 10087.9 10253.4 10283.8 10000.9 10116.8 10292.5 10342.0 10344.8 03:28:48 INFO - PROCESS | 13918 | -------- Summary: end -------- 03:28:48 INFO - PROCESS | 13918 | 03:28:48 INFO - TEST-INFO | 13918: exit 0 03:28:48 INFO - TEST-OK | dromaeo_css | took 1113270ms 03:28:48 INFO - TEST-START | kraken 03:28:48 INFO - operating with platform_type : linux_ 03:28:48 INFO - Initialising browser for kraken test... 03:28:48 INFO - TEST-INFO | started process 14101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprG4Y91/profile http://localhost:47145/getInfo.html) 03:28:53 INFO - PROCESS | 14101 | __metrics Screen width/height:1600/1200 03:28:53 INFO - PROCESS | 14101 | colorDepth:24 03:28:53 INFO - PROCESS | 14101 | Browser inner width/height: 1024/697 03:28:53 INFO - PROCESS | 14101 | __metrics 03:28:54 ERROR - PROCESS | 14101 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:28:54 INFO - TEST-INFO | 14101: exit 0 03:28:54 INFO - Browser initialized. 03:28:54 INFO - Running cycle 1/1 for kraken test... 03:28:54 INFO - TEST-INFO | started process 14181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprG4Y91/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:29:06 INFO - PROCESS | 14181 | RSS: Main: 154980352 03:29:06 INFO - PROCESS | 14181 | 03:29:09 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-beat-detection) 03:29:10 INFO - PROCESS | 14181 | RSS: Main: 210571264 03:29:10 INFO - PROCESS | 14181 | 03:29:12 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-dft) 03:29:13 INFO - PROCESS | 14181 | RSS: Main: 199049216 03:29:13 INFO - PROCESS | 14181 | 03:29:16 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-fft) 03:29:17 INFO - PROCESS | 14181 | RSS: Main: 267726848 03:29:17 INFO - PROCESS | 14181 | 03:29:19 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47145/tests/kraken/driver.html?testName=audio-oscillator) 03:29:19 INFO - PROCESS | 14181 | RSS: Main: 177065984 03:29:19 INFO - PROCESS | 14181 | 03:29:21 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47145/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:29:22 INFO - PROCESS | 14181 | RSS: Main: 181325824 03:29:22 INFO - PROCESS | 14181 | 03:29:25 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47145/tests/kraken/driver.html?testName=imaging-darkroom) 03:29:26 INFO - PROCESS | 14181 | RSS: Main: 217378816 03:29:26 INFO - PROCESS | 14181 | 03:29:29 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47145/tests/kraken/driver.html?testName=imaging-desaturate) 03:29:30 INFO - PROCESS | 14181 | RSS: Main: 217796608 03:29:30 INFO - PROCESS | 14181 | 03:29:32 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47145/tests/kraken/driver.html?testName=json-parse-financial) 03:29:33 INFO - PROCESS | 14181 | RSS: Main: 218099712 03:29:33 INFO - PROCESS | 14181 | 03:29:35 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47145/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:29:35 INFO - PROCESS | 14181 | RSS: Main: 205012992 03:29:35 INFO - PROCESS | 14181 | 03:29:37 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:29:37 INFO - PROCESS | 14181 | RSS: Main: 208973824 03:29:37 INFO - PROCESS | 14181 | 03:29:40 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:29:40 INFO - PROCESS | 14181 | RSS: Main: 218816512 03:29:40 INFO - PROCESS | 14181 | 03:29:43 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:29:43 INFO - PROCESS | 14181 | RSS: Main: 227061760 03:29:43 INFO - PROCESS | 14181 | 03:29:46 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:29:47 INFO - PROCESS | 14181 | RSS: Main: 224968704 03:29:47 INFO - PROCESS | 14181 | 03:29:48 INFO - PROCESS | 14181 | Cycle 1(1): loaded http://localhost:47145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47145/tests/kraken/driver.html?testName=ai-astar) 03:29:49 INFO - PROCESS | 14181 | RSS: Main: 269963264 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | __start_tp_report 03:29:49 INFO - PROCESS | 14181 | _x_x_mozilla_page_load 03:29:49 INFO - PROCESS | 14181 | _x_x_mozilla_page_load_details 03:29:49 INFO - PROCESS | 14181 | |i|pagename|runs| 03:29:49 INFO - PROCESS | 14181 | |0;ai-astar;111;104;112;112;110;111;110;109;108;110 03:29:49 INFO - PROCESS | 14181 | |1;audio-beat-detection;153;123;128;186;133;151;155;126;183;126 03:29:49 INFO - PROCESS | 14181 | |2;audio-dft;161;160;159;161;159;161;161;161;161;161 03:29:49 INFO - PROCESS | 14181 | |3;audio-fft;75;76;74;110;75;75;75;75;101;74 03:29:49 INFO - PROCESS | 14181 | |4;audio-oscillator;82;82;114;82;83;83;130;83;83;128 03:29:49 INFO - PROCESS | 14181 | |5;imaging-gaussian-blur;117;117;117;118;117;117;116;117;117;117 03:29:49 INFO - PROCESS | 14181 | |6;imaging-darkroom;146;146;145;146;145;146;145;146;146;145 03:29:49 INFO - PROCESS | 14181 | |7;imaging-desaturate;96;95;96;95;94;94;95;95;94;95 03:29:49 INFO - PROCESS | 14181 | |8;json-parse-financial;54;52;53;53;53;54;55;76;51;50 03:29:49 INFO - PROCESS | 14181 | |9;json-stringify-tinderbox;62;62;61;62;63;62;62;63;62;62 03:29:49 INFO - PROCESS | 14181 | |10;stanford-crypto-aes;79;80;79;78;79;79;85;80;80;82 03:29:49 INFO - PROCESS | 14181 | |11;stanford-crypto-ccm;122;118;131;117;117;122;118;119;168;127 03:29:49 INFO - PROCESS | 14181 | |12;stanford-crypto-pbkdf2;161;161;160;161;164;158;159;161;157;161 03:29:49 INFO - PROCESS | 14181 | |13;stanford-crypto-sha256-iterative;64;63;65;64;64;69;63;63;62;62 03:29:49 INFO - PROCESS | 14181 | __end_tp_report 03:29:49 INFO - PROCESS | 14181 | __start_cc_report 03:29:49 INFO - PROCESS | 14181 | _x_x_mozilla_cycle_collect,1664 03:29:49 INFO - PROCESS | 14181 | __end_cc_report 03:29:49 INFO - PROCESS | 14181 | __startTimestamp1464172189076__endTimestamp 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | ------- Summary: start ------- 03:29:49 INFO - PROCESS | 14181 | Number of tests: 14 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:2.36 (2.1%) stddev-sans-first:2.46 03:29:49 INFO - PROCESS | 14181 | Values: 111.0 104.0 112.0 112.0 110.0 111.0 110.0 109.0 108.0 110.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#1] audio-beat-detection Cycles:10 Average:146.40 Median:152.00 stddev:23.44 (15.4%) stddev-sans-first:24.74 03:29:49 INFO - PROCESS | 14181 | Values: 153.0 123.0 128.0 186.0 133.0 151.0 155.0 126.0 183.0 126.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 03:29:49 INFO - PROCESS | 14181 | Values: 161.0 160.0 159.0 161.0 159.0 161.0 161.0 161.0 161.0 161.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:13.10 (17.5%) stddev-sans-first:13.71 03:29:49 INFO - PROCESS | 14181 | Values: 75.0 76.0 74.0 110.0 75.0 75.0 75.0 75.0 101.0 74.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:82.50 stddev:20.43 (24.8%) stddev-sans-first:21.13 03:29:49 INFO - PROCESS | 14181 | Values: 82.0 82.0 114.0 82.0 83.0 83.0 130.0 83.0 83.0 128.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 03:29:49 INFO - PROCESS | 14181 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 116.0 117.0 117.0 117.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:29:49 INFO - PROCESS | 14181 | Values: 146.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 145.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 03:29:49 INFO - PROCESS | 14181 | Values: 96.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 94.0 95.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:53.50 stddev:7.49 (14.0%) stddev-sans-first:7.93 03:29:49 INFO - PROCESS | 14181 | Values: 54.0 52.0 53.0 53.0 53.0 54.0 55.0 76.0 51.0 50.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 03:29:49 INFO - PROCESS | 14181 | Values: 62.0 62.0 61.0 62.0 63.0 62.0 62.0 63.0 62.0 62.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.00 stddev:2.02 (2.5%) stddev-sans-first:2.11 03:29:49 INFO - PROCESS | 14181 | Values: 79.0 80.0 79.0 78.0 79.0 79.0 85.0 80.0 80.0 82.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:122.00 stddev:15.50 (12.7%) stddev-sans-first:16.37 03:29:49 INFO - PROCESS | 14181 | Values: 122.0 118.0 131.0 117.0 117.0 122.0 118.0 119.0 168.0 127.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:161.00 stddev:1.95 (1.2%) stddev-sans-first:2.05 03:29:49 INFO - PROCESS | 14181 | Values: 161.0 161.0 160.0 161.0 164.0 158.0 159.0 161.0 157.0 161.0 03:29:49 INFO - PROCESS | 14181 | 03:29:49 INFO - PROCESS | 14181 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:2.02 (3.2%) stddev-sans-first:2.15 03:29:49 INFO - PROCESS | 14181 | Values: 64.0 63.0 65.0 64.0 64.0 69.0 63.0 63.0 62.0 62.0 03:29:49 INFO - PROCESS | 14181 | -------- Summary: end -------- 03:29:49 INFO - PROCESS | 14181 | 03:29:50 INFO - TEST-INFO | 14181: exit 0 03:29:50 INFO - TEST-OK | kraken | took 61234ms 03:29:50 INFO - SUITE-END | took 1174s 03:29:50 INFO - Completed test suite (00:19:34) 03:29:50 INFO - javascript benchmark 03:29:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.2219445138717, 3118.610173728284, 3142.5443310719947, 3153.2274592724784, 3178.570892929455, 5310.70791106489, 5313.814016509423, 5323.60072994525, 5404.675719456831, 5416.783328666858, 5411.783528658859, 5413.675179489233, 5417.485338892809, 5445.945540544593, 5469.343678758544, 4853.417589889218, 4947.727874966876, 4948.901021979558, 4952.826651067214, 5079.492050794928, 4993.0263598598285, 4993.6254780891395, 5033.295338652591, 5063.063333283354, 5064.848054558369, 4842.854714358575, 4879.878003049899, 4891.755412229374, 4963.6277279204205, 4964.87587810304, 4944.505549445063, 4948.059868624986, 4949.752512374367, 4951.183054795954, 4960.677555958887, 4834.033193361342, 4872.902541949141, 4875.219964805649, 4886.926696099561, 4895.828645997409, 5290.444503327172, 5352.759125839308, 5360.544353729938, 5427.864303392407, 5432.972835135799, 5854.18041474194, 5871.79448719297, 5873.089671100944, 5874.5006674432725, 5880.852978675567, 5716.256886604757, 5718.456746609066, 5762.164486149527, 5763.337216220147, 5823.068309070529, 5386.407495175527, 5425.213344065089, 5433.1850222466555, 5446.863828404282, 5451.563874889999, 5810.622309549865, 5948.226730525004, 5961.5230781537375, 5962.433568810956, 5963.701814909324, 5618.634788738719, 5664.5751568631695, 5680.801171959044, 5752.050911599539, 5760.2223699800825, 5806.187133801272, 5808.419158084158, 5816.854578635568, 5843.503302219359, 5857.121431785266, 5590.888182236333, 5637.7181140942785, 5785.508231800344, 5790.160426738099, 5790.565707571949, 5764.337101233328, 5805.8548536286935, 5822.33043200029, 5834.474897259267, 5837.182794408786, 4435.955640443619, 4438.289873620205, 4447.644188464915, 4452.955470445254, 4491.0, 3897.376419772823, 3918.8628398005935, 3944.15200731844, 3995.34076877312, 4015.31739604268, 5453.100238460689, 5507.807226747044, 5509.449055094458, 5509.889802204014, 5559.416261292587, 5818.44724751148, 5832.387599302097, 5835.387284335084, 5837.357890632027, 5895.469407753323, 5330.92003619946, 5439.646422982493, 5440.537554307928, 5483.067878460672, 5499.010178167888, 5018.447970723218, 5033.949660503421, 5040.823571175065, 5042.873928151752, 5075.56857667095, 6013.9699301503215, 6052.788152414732, 6061.939380606225, 6067.696615169224, 6069.848253793602, 5697.373288938296, 5780.797672081457, 5798.043322851682, 5864.413558644272, 5906.40935906406, 6078.6048906820915, 6084.330723620486, 6085.543584231289, 6119.571629986036, 6139.048447490646, 5722.828315150552, 5733.140028995684, 5734.483896449339, 5735.13972904051, 5739.598228123991, 5694.886102278014, 5731.484166425042, 5738.340090889601, 5850.502707269759, 5853.824385268449, 5637.605367624391, 5669.829905102853, 5676.0, 5686.34607020198, 5711.0, 5672.035753921926, 5682.829515114553, 5682.971585142213, 5683.261176047087, 5685.403032681675, 5461.590380721541, 5498.917516237339, 5501.944980550304, 5545.1682247663175, 5579.944200557942, 5690.829275121753, 5692.345380281321, 5728.770849165987, 5729.828105156852, 5729.914051289151, 5495.862603434946, 5561.138023606348, 5562.72186390687, 5568.916466252928, 5609.0], "value": 5349.904323156829, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.2551723104732, 634.1787385335987, 643.189581127779, 649.0815496073056, 660.6201434175348, 44521.55478445214, 45879.541204587986, 45948.08103837921, 45986.770066149664, 49014.26478602815, 46935.0, 47720.761396193055, 47847.76076119623, 47903.52096479034, 47945.0, 8896.199342059213, 9081.909180908173, 9084.091590840897, 9084.318676099287, 9084.818303633923, 8922.86615700765, 9013.909860901405, 9030.0, 9033.277337812991, 9033.819323613556, 9075.63697452101, 9121.908780912205, 9131.543422828832, 9139.908600914005, 9142.582899650532, 9023.503707296097, 9089.545522723838, 9096.8635470468, 9098.090190980849, 9100.408473449204, 8862.069482704288, 8940.64237430502, 8956.462612243222, 8956.820863582692, 8960.372773905829, 42821.57178428238, 43175.56824431779, 43327.0, 43334.34998475025, 43334.56665433337, 3498.3702933471964, 3650.6166852480637, 3696.353138200831, 3919.627635374635, 4151.398047283157, 33139.50290745641, 33294.6670533294, 33601.49597756036, 33623.159421014425, 35245.47131793025, 4232.724872883253, 4296.2481565725875, 4321.842286124082, 4352.738835669838, 4406.845760398371, 340694.9999999975, 341061.0, 343055.0, 343713.0, 345974.0, 61734.0, 61903.0, 62110.0, 62116.37883621106, 62121.37878621247, 63952.0, 64204.357956420776, 64205.678971604844, 64228.39429014312, 64252.0, 17220.913895430444, 17503.73744393835, 17517.299308027792, 17520.64958700819, 17526.123693815258, 13083.738325233633, 13160.60518184456, 13184.0, 13228.0, 13228.07403481747, 15983.871384631315, 18356.908215458836, 18638.72041919345, 18785.7182142268, 19057.523561911064, 5325.547328477122, 6100.359462256488, 6456.225252969673, 6456.451201647913, 6488.7728929488185, 991.2367477042781, 1013.8428543575758, 1049.7480604654982, 1060.7878424315168, 1065.7158124460007, 30722.67604793961, 32612.67387326144, 32628.205448700075, 32790.344193116005, 33330.66669333324, 1021.8518314844306, 1093.9179561532758, 1126.6732647532288, 1141.9714507137387, 1158.5481662151767, 16508.25712842925, 17074.82925170757, 17101.657966840594, 17203.397881074103, 17422.303107875796, 540237.0, 540877.0, 542931.0, 543548.0, 547052.0, 59766.70116649476, 59914.70042649846, 60013.699931500065, 60075.39924600785, 60227.39772602305, 1242.2236102435977, 1350.6218258887527, 1366.480737319812, 1388.0561218371604, 1408.1199250468458, 32614.51078233781, 33802.14088225171, 34125.31749365049, 34167.487487687205, 34323.14192145217, 46215.76892115518, 51517.742411287705, 52093.21860172176, 52137.217941730145, 52153.0, 408577.0, 410070.0, 412594.0, 412818.0, 413942.0, 25406.364840878385, 27218.183454496397, 28241.43517129605, 28352.716472835833, 28685.0, 49228.0, 49570.752146239036, 49811.25283120684, 50191.0, 50218.24672629841, 41363.379549306184, 41743.37384939289, 42134.78932605317, 42911.78544107259, 43445.56554434415, 41045.0, 41557.16885662209, 41878.79060604677, 41931.58068419277, 42422.36366454567, 42003.36994945017, 42815.0, 43163.97852150445, 43234.13531729411, 43255.0, 35638.28723425569, 36628.633713663585, 36801.63198367982, 37711.24577508489, 37878.2424351517, 28420.43159136847, 28683.0, 28747.28131796722, 28820.0, 28834.42331153323], "value": 20460.092073069332, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.1851493349034, 386.29693956998256, 394.34144977886933, 397.71563332217494, 408.397613520058, 17947.10264486777, 17962.91018544907, 20088.4977875553, 20128.899355503203, 20137.798622013775, 12191.512339506431, 12370.50517979282, 12387.318697471635, 12401.13192076555, 17244.396446124356, 5058.772355244023, 5204.609654275925, 5241.633085684004, 5243.161094224924, 5248.685078895279, 3605.8197090145386, 5549.556035517149, 5869.383714709958, 5878.647281163123, 5922.230110085704, 5825.679587622679, 5917.8816423671715, 5921.052631578948, 5921.792737254197, 5944.851378715523, 5794.768209271624, 5895.233619629464, 5914.585978981473, 5929.525637948976, 5929.703514824263, 3481.3037392521596, 3737.9439308410397, 3740.233252183296, 5236.633435659505, 5646.576506761969, 13718.65703357414, 13813.378397972016, 13825.308734563232, 20365.898170509052, 21403.464913377135, 2307.227078928544, 2311.433698743802, 2359.3629719975684, 2411.5418070566534, 2437.5490534251044, 9378.155965963097, 9601.90398096024, 9682.724559728458, 9768.218542516654, 9789.510524473819, 1714.3057061889954, 1872.7565416495904, 1888.1314595286185, 1903.8767127394904, 1921.6637088509594, 76909.61545192295, 78865.0, 79637.0, 80355.0, 80583.5970820148, 8980.640774369083, 9504.334696571173, 9802.509874506246, 10036.243657359933, 10142.18862490999, 12795.232286062866, 12884.355782210852, 12894.097413181174, 12904.29026403538, 12927.418266178045, 7734.574598397142, 7764.767056988299, 7907.881381779278, 8050.476719013245, 8060.274575288252, 8061.919380806235, 8069.878951815727, 8126.065502467174, 8194.918050819415, 8247.670093196324, 3940.862069827542, 3985.4021896715726, 4009.69927255457, 4046.4537287466405, 4169.60388763067, 1834.2316850820696, 1836.724491326285, 2040.1839264294103, 2061.2373421833718, 2077.9064942077644, 891.4294851295176, 959.3332633819429, 962.7785609309898, 1044.2690116918188, 1047.984280235797, 18712.812871871378, 18923.148458319134, 18973.241070357308, 19285.4214373569, 19530.0, 970.6505657963128, 1000.3347773730533, 1023.181454836143, 1042.6298663974333, 1045.1168762395764, 10451.0, 10890.074343680875, 10954.945225273823, 11008.559657613605, 11027.669169924915, 491319.0, 496350.0, 496765.0, 506989.0, 509916.0, 88208.5589572048, 90335.54832225796, 90550.0, 91391.54304228436, 91536.08463915408, 964.4117088575963, 992.2905122837207, 994.4878387630521, 1006.5671761142632, 1009.0666133826181, 16580.0880951544, 16750.66498670044, 16994.49016529506, 17038.57403564921, 17222.91388543049, 38821.41767873541, 39630.80184599059, 40958.0, 42015.15969680528, 42193.0, 55193.724031379585, 57340.42659573351, 57540.08240963388, 58214.708926455096, 58539.70730146322, 7347.08161479815, 7547.358474529727, 8256.380771442286, 8348.749537513884, 8406.495610263404, 12792.74414511723, 12917.870821292043, 13061.673458163621, 13286.867131328563, 13431.261280629658, 11666.824997624874, 11776.764464710486, 11783.469743861719, 12194.87805121973, 12228.266304021787, 11573.074154067823, 11593.18847680688, 11669.59156429521, 11693.415329233334, 11947.402629868646, 11374.374409407563, 11841.171118021655, 11898.881011189776, 12097.334646594521, 12434.502619895104, 7779.18318576564, 7850.332721718718, 7893.486923349964, 8074.798130046795, 8233.423660343718, 10108.292419530857, 10895.182861285277, 10953.890461095605, 11115.277506962346, 11338.94330528375], "value": 8760.385292915005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.9416826242818, 1479.711456266028, 1504.8269449013367, 1684.7220208665572, 1841.5212044868338, 8210.302124319433, 8592.01191862936, 8597.570121493924, 8814.86777698335, 8824.647014119428, 8516.489010659348, 8746.86879696805, 8769.649214031431, 8785.428947118433, 8797.560121993907, 2001.1095062697107, 2073.626747185506, 2077.2937201351538, 2377.6433534969765, 2401.2916189724074, 2314.618088015484, 2375.0737212487143, 2383.094424118832, 2387.462820865309, 2393.8563686178886, 2224.443889027743, 2241.428435748882, 2243.876586787726, 2253.9323820285335, 2306.192832508617, 2002.6695595226915, 2274.442761523421, 2368.052778888441, 2397.0531640002187, 2399.436132508859, 2355.2580937004786, 2381.84518006329, 2390.2829151254555, 2392.629142482918, 2406.290144407404, 8284.46151000183, 8340.791480212982, 8367.748967530984, 8367.874481882776, 8368.748937531884, 5159.380874295071, 5278.9208161877605, 5284.365876094855, 5291.497307755757, 5302.814401495968, 3144.308252184516, 3164.27221738999, 3289.2105894585366, 3319.452290372086, 3324.9168770780684, 1104.1222228328497, 1115.7935781880378, 1128.136975213962, 1151.2171723228203, 1162.4071723421116, 11754.764904701859, 11944.283342999448, 11982.760344793056, 11983.820242696367, 12037.398130093548, 4599.678022538457, 4760.571548560577, 4821.0, 4821.566059054632, 4827.589654879374, 10193.898061019296, 10219.335743176669, 10222.539985700661, 10253.077223049811, 10260.230482713827, 5360.65155764874, 5365.034293827149, 5366.221897824794, 5369.5972802040005, 5381.48875856793, 4321.157374312042, 4680.648951328664, 4724.173269677793, 4753.691010084333, 4847.369841920598, 3969.4839670843185, 4022.5374081980362, 4075.4294398784195, 4098.405731168964, 4150.5641907599875, 1285.5436320106414, 1329.1227789658972, 1329.3154025676783, 1351.3108314759543, 1355.159800923414, 880.4673172730534, 967.0909345215475, 971.523953262896, 990.0841721407813, 991.1624677147798, 5898.4101589840675, 6017.398260173947, 6108.847278818065, 6115.205023346936, 6133.1413602095745, 424.2554317173341, 424.2829617945662, 425.5255390239922, 427.80534856640156, 446.35055993529335, 4135.0, 4151.564085771011, 4235.512916014698, 4285.97857010713, 4296.570342965679, 10025.949870250748, 10120.544575494121, 10352.171826254033, 10601.57593696259, 10606.946965265124, 10630.0, 10671.039606435457, 10744.086752626114, 10795.29830561011, 10814.945925270324, 7880.842383152305, 8232.094469608219, 8238.464499807613, 8245.546494942835, 8246.422750407413, 5796.101604251347, 5956.315023772322, 6008.188894499187, 6080.969595151996, 6118.938810611837, 9550.283728720513, 9582.520873956413, 9673.37123086997, 9692.757681057748, 9724.173445257233, 9705.514724263618, 9890.258230632588, 9902.851457228291, 9939.602415903282, 9979.800403992025, 6956.339147780953, 7197.316254955771, 7356.264373562601, 7717.421193410629, 7833.5691536966015, 8207.466514676527, 8209.138040505899, 8260.215279548433, 8287.12985136576, 8290.046644635944, 7290.3438690518105, 7296.087989001375, 7498.775036748906, 7562.357199638092, 7598.316151546387, 7103.644817759195, 7194.640267986475, 7276.854462910818, 7313.085864266966, 7404.259574042768, 7060.258672839484, 7144.214136444883, 7144.4641651877355, 7149.14210294768, 7156.0, 5064.607233010952, 5225.895482090261, 5262.394824595068, 5285.075111855487, 5289.523942845162, 5208.635395522277, 5271.103912334989, 5287.048331300402, 5313.468653134656, 5385.8114965976], "value": 4504.630232422536, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.4337963355776, 316.91601725542733, 322.4244723169144, 324.40147927074537, 326.2577635878378, 5287.92068118978, 5314.282571852798, 5343.919841202385, 5356.196570514425, 5391.56867450604, 5228.4771522847695, 5253.290805741224, 5262.000219958205, 5274.520975141677, 5301.6818990860575, 4084.3254265751048, 4184.121334519755, 4189.664826813848, 4212.810423530948, 4220.97889510552, 4150.5719154294775, 4155.979220103895, 4158.773161917242, 4162.125953549758, 4180.0, 4067.227226826908, 4115.176964607075, 4129.855455059073, 4139.337705967046, 4147.771872547009, 3876.6704830089193, 3943.7239393242485, 4102.958970410287, 4104.774237416941, 4111.71218014739, 3574.891783547109, 3606.0263728793343, 3608.6752192302556, 3629.709623230135, 3631.8547258109643, 5065.873353166164, 5090.694558326475, 5093.516115968977, 5097.3118628984985, 5131.358580177478, 4424.292113261895, 4521.208788462039, 4626.236670949323, 4631.791569379352, 4683.063387322515, 5458.153986132156, 5461.617686761928, 5463.562914966793, 5463.945360546383, 5471.890562188734, 3874.6900247980375, 3897.5907529709257, 3898.8050597470015, 3922.5489068757174, 3946.8223929922933, 5478.780848766045, 5726.0838265877255, 5745.511631511368, 5793.102069179284, 5811.2566230131215, 4013.458183145238, 4015.759054456742, 4019.115794525258, 4031.838726450968, 4040.6463834615734, 4040.777757223322, 4044.5753195914594, 4063.8577649782114, 4073.2260870434525, 4082.4692789937712, 4436.511983681792, 4648.604868586208, 4652.32541281519, 4686.5782079613, 4722.882897287774, 5503.257060296889, 5531.340597820637, 5542.806001789999, 5549.472800083986, 5568.49883510486, 1367.8290213723285, 1381.88944884409, 1385.0263374691026, 1393.6724869655623, 1394.5607133752835, 851.7529916324197, 889.5285498685707, 893.9374243802871, 904.2810965282616, 912.2109375390372, 4760.309755085494, 4774.355462012653, 4789.87437952081, 4809.663323567387, 4818.590419814285, 5045.621578381636, 5077.898442031139, 5124.359455068116, 5135.922961155586, 5176.922346164811, 4650.325702773079, 4771.308160316804, 4803.639727020418, 4849.2968519564465, 4882.7558622068755, 2922.8392438415667, 2946.703450481781, 3020.758339332848, 3026.6973302669558, 3034.908952731422, 5764.654120752768, 5799.130130480462, 5817.563682723812, 5833.329167145747, 5849.61977471463, 5373.919391209135, 5665.685573939291, 5723.998300297515, 5770.653760774367, 5824.291704357494, 5801.448862358069, 5857.096443655914, 5869.413058694096, 5895.55783316253, 5939.732712027969, 5422.186671999231, 5636.182753500638, 5648.971755141198, 5677.3754886963225, 5702.657840529581, 5616.297962754656, 5622.606417550732, 5656.218291238411, 5657.858553536194, 5665.716714164358, 5807.8548036299435, 5811.476967072984, 5819.418058194146, 5824.767009319579, 5851.678157701367, 3801.4867992820614, 3952.9407058894717, 4005.7796821175116, 4014.437978682928, 4026.67786577079, 5670.554008727748, 5682.403347648437, 5718.857028574319, 5739.282589676291, 5817.505512031525, 5583.330000399978, 5592.524635405874, 5597.524210442158, 5598.0, 5599.244102046172, 5349.705766182898, 5467.414449809511, 5530.059889818821, 5546.528545073714, 5558.527525160407, 5452.236686863763, 5454.4545545445135, 5465.945340546544, 5471.781128754806, 5486.31421072366, 5443.0607471964695, 5531.668099914018, 5551.945780061403, 5564.721763911707, 5587.636803607753, 5417.295751552273, 5441.619086663895, 5477.698726569917, 5488.533474654699, 5496.835094947158], "value": 4163.720818306407, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.8906664000399, 814.8370325934815, 823.5964377455045, 849.1084361420507, 859.4542465534383, 13788.172709637429, 14136.787948180772, 15170.924145379271, 15356.6928661427, 15393.0, 13333.466661333536, 13337.866621333784, 13342.399592018359, 13414.597562073153, 13464.192148471098, 668.802703202554, 755.8563872864165, 791.1139523733426, 793.4683761879554, 805.3758337288618, 796.9362451003906, 799.4643588795522, 802.6107337941094, 804.1194891593696, 804.9396295277849, 848.8005318750089, 850.8128211793396, 850.9958249265894, 853.7908212487918, 856.2122846980793, 698.078536332042, 724.1925253342538, 724.463896716426, 817.8609636361773, 840.7141571865565, 620.8106527509108, 623.7847942459844, 623.8783437229743, 624.2883113250898, 624.7688355308519, 12166.391680415943, 12174.0, 12184.573539926148, 12186.451609677586, 12207.145499815017, 13637.863621363758, 13739.725205495834, 13824.377902994289, 13852.584422467342, 13978.790318145237, 12771.233725976472, 12804.359782010955, 12824.487020519167, 12852.0, 12985.285809280485, 4446.221911165566, 4468.374427580141, 4529.818807247706, 4696.412948381452, 4728.385309909724, 62058.68970655163, 62263.37736622621, 63161.68419157921, 63839.999999999534, 63887.0, 11580.594679186357, 11644.475998580085, 11690.590829320932, 11707.590234341927, 12152.5138994441, 19641.41075767729, 19641.901790491243, 19642.919639419968, 19644.70532942007, 19662.11520481582, 8112.553809540414, 8177.223163799429, 8177.509349439053, 8226.670933162606, 8448.535330556879, 4631.976840115778, 4646.163690535668, 4719.38647975761, 4726.574608285271, 4790.089882922233, 3387.3733359328603, 3493.039414161126, 3517.4547945068557, 3518.964810351915, 3519.63043880394, 639.0669622351326, 645.4868379638199, 650.4568685147955, 660.0594153331483, 671.70445004198, 605.0772571827997, 652.885744994624, 665.3679004945321, 670.7015378156674, 684.1550684904139, 8649.308055355556, 8746.387752857367, 8786.912130878738, 8846.071162527971, 8931.788803322079, 540.2949151357516, 650.5381179362611, 672.2067959807474, 679.5277282288861, 697.6790676288863, 4188.916221675549, 4321.092570560218, 4377.671674624416, 4389.670774691911, 4479.910401791946, 70094.0, 71559.64220178865, 73174.63412682903, 73418.26581734115, 73510.26489735142, 60765.39234607686, 61196.0, 61878.690606546676, 62251.0, 64105.35894641087, 56103.71948140233, 56271.437285627435, 56581.71709141428, 57895.71052144797, 58388.99999999915, 8267.751967440987, 8388.119247478928, 8402.7011168461, 8425.95787021061, 8602.39783215169, 34171.31657366888, 34892.0, 34919.82540087385, 36186.45720314145, 38616.22767544577, 49580.50419495759, 49913.50086499234, 50219.24232651986, 51021.23468148055, 51176.48823511866, 12720.618381448572, 12732.681682958, 12839.486420543073, 13046.217226966412, 13113.378163687443, 11832.349220792596, 11849.407529623311, 12104.51581936748, 12858.614241572393, 13404.32978351098, 11467.483963221508, 11720.0, 11724.648260552198, 11746.70633234176, 11814.645560633195, 11462.197646164688, 11677.824832627348, 11830.101201455569, 11833.704157396134, 12384.75230495403, 11710.473028713557, 12042.096842736655, 12087.51649934028, 12180.147389682637, 12205.084618653815, 9955.900440995787, 10079.24405669563, 10087.899121008695, 10253.384796912209, 10283.845742313722, 10000.899990999997, 10116.797664046826, 10292.536835842255, 10342.0, 10344.793104137725], "value": 6790.847740216222, "unit": "score"}], "name": "dromaeo_css", "value": 7043.9247651726255, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 112.0, 112.0, 110.0, 111.0, 110.0, 109.0, 108.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 128.0, 186.0, 133.0, 151.0, 155.0, 126.0, 183.0, 126.0], "value": 146.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 161.0, 159.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 110.0, 75.0, 75.0, 75.0, 75.0, 101.0, 74.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 114.0, 82.0, 83.0, 83.0, 130.0, 83.0, 83.0, 128.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 53.0, 53.0, 54.0, 55.0, 76.0, 51.0, 50.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 78.0, 79.0, 79.0, 85.0, 80.0, 80.0, 82.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 118.0, 131.0, 117.0, 117.0, 122.0, 118.0, 119.0, 168.0, 127.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 164.0, 158.0, 159.0, 161.0, 157.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 64.0, 64.0, 69.0, 63.0, 63.0, 62.0, 62.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1497.5, "alertThreshold": 2.0}]} 03:29:50 INFO - Return code: 0 03:29:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:29:50 ERROR - # TBPL SUCCESS # 03:29:50 INFO - Running post-action listener: _resource_record_post_action 03:29:50 INFO - ##### 03:29:50 INFO - ##### Finished run-tests step (success) 03:29:50 INFO - ##### 03:29:50 INFO - Running post-run listener: _resource_record_post_run 03:29:50 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537702400; Read time: 16; Write time: 459656 03:29:50 INFO - TinderboxPrint: CPU usage
13.2% 03:29:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 03:29:50 INFO - TinderboxPrint: I/O write bytes / time
537,702,400 / 459,656 03:29:50 INFO - TinderboxPrint: CPU idle
8,260.8 (86.8%) 03:29:50 INFO - TinderboxPrint: CPU user
1,207.2 (12.7%) 03:29:50 INFO - TinderboxPrint: Swap in / out
0 / 0 03:29:50 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247050240; Read time: 0; Write time: 230036 03:29:50 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290652160; Read time: 16; Write time: 229620 03:29:51 INFO - Running post-run listener: _upload_blobber_files 03:29:51 INFO - Blob upload gear active. 03:29:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:29:51 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 03:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:29:51 INFO - (blobuploader) - INFO - Open directory for files ... 03:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:55 INFO - (blobuploader) - INFO - Done attempting. 03:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:55 INFO - (blobuploader) - INFO - Done attempting. 03:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:56 INFO - (blobuploader) - INFO - Done attempting. 03:29:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:29:56 INFO - Return code: 0 03:29:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:29:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:29:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4088c9d0d06997b83dd1dc69016796ab81814d42dac464631338cbc63bf131a98cdaaa9b86ec5c2bf8f896f8afb9bb23ce5ffdc2018cc9ed3be895685e2b525a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1ca5a6d54216387236125d6a658d72892a3bc0e08cad63d510cc96977e524c916f0f5f500b3287c13800e9a4f8711274f3a271b62a2a392b600ddbb43a45e01d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9d22168725f34fb2773c86029d67e49e14842d968adfa37c9de878de448f92011c55a52b2abcef45c4aa4cde77c2b46b84b97c86f28101863d9c30fac26b5065"} 03:29:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:29:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:29:56 INFO - Contents: 03:29:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4088c9d0d06997b83dd1dc69016796ab81814d42dac464631338cbc63bf131a98cdaaa9b86ec5c2bf8f896f8afb9bb23ce5ffdc2018cc9ed3be895685e2b525a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1ca5a6d54216387236125d6a658d72892a3bc0e08cad63d510cc96977e524c916f0f5f500b3287c13800e9a4f8711274f3a271b62a2a392b600ddbb43a45e01d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9d22168725f34fb2773c86029d67e49e14842d968adfa37c9de878de448f92011c55a52b2abcef45c4aa4cde77c2b46b84b97c86f28101863d9c30fac26b5065"} 03:29:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:29:56 INFO - Copying logs to upload dir... 03:29:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:29:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.587935 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-05-25 03:29:56.497879) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 03:29:56.504679) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4088c9d0d06997b83dd1dc69016796ab81814d42dac464631338cbc63bf131a98cdaaa9b86ec5c2bf8f896f8afb9bb23ce5ffdc2018cc9ed3be895685e2b525a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1ca5a6d54216387236125d6a658d72892a3bc0e08cad63d510cc96977e524c916f0f5f500b3287c13800e9a4f8711274f3a271b62a2a392b600ddbb43a45e01d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9d22168725f34fb2773c86029d67e49e14842d968adfa37c9de878de448f92011c55a52b2abcef45c4aa4cde77c2b46b84b97c86f28101863d9c30fac26b5065"} build_url:https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/eXlwUhHNSB6JX3dRsgt3lA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4088c9d0d06997b83dd1dc69016796ab81814d42dac464631338cbc63bf131a98cdaaa9b86ec5c2bf8f896f8afb9bb23ce5ffdc2018cc9ed3be895685e2b525a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1ca5a6d54216387236125d6a658d72892a3bc0e08cad63d510cc96977e524c916f0f5f500b3287c13800e9a4f8711274f3a271b62a2a392b600ddbb43a45e01d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9d22168725f34fb2773c86029d67e49e14842d968adfa37c9de878de448f92011c55a52b2abcef45c4aa4cde77c2b46b84b97c86f28101863d9c30fac26b5065"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 03:29:56.535911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:29:56.536240) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1464167852.568421-1416872513 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:29:56.618374) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 03:29:56.618704) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 03:29:56.619002) ========= ========= Total master_lag: 0.20 =========