builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0237 starttime: 1464669973.45 results: success (0) buildid: 20160530205547 builduid: 24a50bb8b1724a29ba7d9caa2938ebc1 revision: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:13.449968) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:13.450571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:13.450896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:13.474519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:13.474834) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bdPhAZHaXz/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 21:46:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-05-30 21:46:14 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.622744 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:14.118961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:14.119342) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bdPhAZHaXz/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020613 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:14.168422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 21:46:14.168832) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bdPhAZHaXz/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 21:46:14,154 truncating revision to first 12 chars 2016-05-30 21:46:14,154 Setting DEBUG logging. 2016-05-30 21:46:14,154 attempt 1/10 2016-05-30 21:46:14,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/42fab251fe11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 21:46:15,483 unpacking tar archive at: fx-team-42fab251fe11/testing/mozharness/ program finished with exit code 0 elapsedTime=1.593209 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 21:46:15.783496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:15.783806) ========= script_repo_revision: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:15.784251) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:15.784531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 21:46:15.801743) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 40 mins, 12 secs) (at 2016-05-30 21:46:15.802167) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bdPhAZHaXz/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 21:46:15 INFO - MultiFileLogger online at 20160530 21:46:15 in /builds/slave/test 21:46:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:46:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:46:15 INFO - {'append_to_log': False, 21:46:15 INFO - 'base_work_dir': '/builds/slave/test', 21:46:15 INFO - 'blob_upload_branch': 'Fx-Team', 21:46:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:46:15 INFO - 'branch': 'Fx-Team', 21:46:15 INFO - 'buildbot_json_path': 'buildprops.json', 21:46:15 INFO - 'config_files': ('talos/mac_config.py',), 21:46:15 INFO - 'default_actions': ('clobber', 21:46:15 INFO - 'read-buildbot-config', 21:46:15 INFO - 'download-and-extract', 21:46:15 INFO - 'populate-webroot', 21:46:15 INFO - 'create-virtualenv', 21:46:15 INFO - 'install', 21:46:15 INFO - 'run-tests'), 21:46:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:46:15 INFO - 'download_minidump_stackwalk': True, 21:46:15 INFO - 'download_symbols': 'ondemand', 21:46:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:46:15 INFO - 'tooltool.py': '/tools/tooltool.py', 21:46:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:46:15 INFO - '/tools/misc-python/virtualenv.py')}, 21:46:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:46:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:46:15 INFO - 'installer_path': 'installer.exe', 21:46:15 INFO - 'log_level': 'info', 21:46:15 INFO - 'log_name': 'talos', 21:46:15 INFO - 'log_to_console': True, 21:46:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:46:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:46:15 INFO - 'opt_config_files': (), 21:46:15 INFO - 'pip_index': False, 21:46:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:46:15 INFO - 'cmd': ('bash', 21:46:15 INFO - '-c', 21:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:46:15 INFO - 'enabled': True, 21:46:15 INFO - 'halt_on_failure': False, 21:46:15 INFO - 'name': 'check_screen_resolution'},), 21:46:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:46:15 INFO - 'cmd': ('bash', 21:46:15 INFO - '-c', 21:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:46:15 INFO - 'enabled': True, 21:46:15 INFO - 'halt_on_failure': False, 21:46:15 INFO - 'name': 'check_screen_resolution'},), 21:46:15 INFO - 'run_cmd_checks_enabled': True, 21:46:15 INFO - 'sps_profile': False, 21:46:15 INFO - 'sps_profile_interval': 0, 21:46:15 INFO - 'suite': 'dromaeojs', 21:46:15 INFO - 'system_bits': '32', 21:46:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:46:15 INFO - 'title': 't-yosemite-r7-0237', 21:46:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:46:15 INFO - 'use_talos_json': True, 21:46:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:46:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:46:15 INFO - 'work_dir': 'build'} 21:46:15 INFO - ##### 21:46:15 INFO - ##### Running clobber step. 21:46:15 INFO - ##### 21:46:15 INFO - Running pre-action listener: _resource_record_pre_action 21:46:15 INFO - Running main action method: clobber 21:46:15 INFO - rmtree: /builds/slave/test/build 21:46:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:46:17 INFO - Running post-action listener: _resource_record_post_action 21:46:17 INFO - ##### 21:46:17 INFO - ##### Finished clobber step (success) 21:46:17 INFO - ##### 21:46:17 INFO - ##### 21:46:17 INFO - ##### Running read-buildbot-config step. 21:46:17 INFO - ##### 21:46:17 INFO - Running pre-action listener: _resource_record_pre_action 21:46:17 INFO - Running main action method: read_buildbot_config 21:46:17 INFO - Using buildbot properties: 21:46:17 INFO - { 21:46:17 INFO - "project": "", 21:46:17 INFO - "product": "firefox", 21:46:17 INFO - "script_repo_revision": "production", 21:46:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:46:17 INFO - "repository": "", 21:46:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:46:17 INFO - "buildid": "20160530205547", 21:46:17 INFO - "pgo_build": "False", 21:46:17 INFO - "basedir": "/builds/slave/test", 21:46:17 INFO - "buildnumber": 44, 21:46:17 INFO - "slavename": "t-yosemite-r7-0237", 21:46:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:46:17 INFO - "platform": "yosemite_r7", 21:46:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:46:17 INFO - "branch": "fx-team", 21:46:17 INFO - "repo_path": "integration/fx-team", 21:46:17 INFO - "revision": "42fab251fe111d5f891c9bde0ee1fb6f7f946a50", 21:46:17 INFO - "stage_platform": "macosx64", 21:46:17 INFO - "builduid": "24a50bb8b1724a29ba7d9caa2938ebc1", 21:46:17 INFO - "slavebuilddir": "test" 21:46:17 INFO - } 21:46:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 21:46:17 INFO - Running post-action listener: _resource_record_post_action 21:46:17 INFO - ##### 21:46:17 INFO - ##### Finished read-buildbot-config step (success) 21:46:17 INFO - ##### 21:46:17 INFO - ##### 21:46:17 INFO - ##### Running download-and-extract step. 21:46:17 INFO - ##### 21:46:17 INFO - Running pre-action listener: _resource_record_pre_action 21:46:17 INFO - Running main action method: download_and_extract 21:46:17 INFO - mkdir: /builds/slave/test/build/tests 21:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:17 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:46:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 21:46:20 INFO - Downloaded 1199 bytes. 21:46:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:46:20 INFO - Using the following test package requirements: 21:46:20 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:46:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 21:46:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 21:46:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'jsshell-mac.zip'], 21:46:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 21:46:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:46:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 21:46:20 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 21:46:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 21:46:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:46:20 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 21:46:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:20 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:46:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:46:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:47:27 INFO - Downloaded 18684709 bytes. 21:47:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:47:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:47:30 INFO - Return code: 0 21:47:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:47:30 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')]} 21:47:30 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:47:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:47:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:47:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:47:31 INFO - Downloaded 18684709 bytes. 21:47:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:47:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:47:34 INFO - Return code: 0 21:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:34 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:47:36 INFO - Downloaded 10834055 bytes. 21:47:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:47:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:47:36 INFO - Return code: 0 21:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:36 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:47:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:47:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:47:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 21:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 21:47:39 INFO - Downloaded 110902438 bytes. 21:47:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:47:39 INFO - mkdir: /builds/slave/test/properties 21:47:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:47:39 INFO - Writing to file /builds/slave/test/properties/build_url 21:47:39 INFO - Contents: 21:47:39 INFO - build_url:https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:47:41 INFO - Running post-action listener: _resource_record_post_action 21:47:41 INFO - Running post-action listener: set_extra_try_arguments 21:47:41 INFO - ##### 21:47:41 INFO - ##### Finished download-and-extract step (success) 21:47:41 INFO - ##### 21:47:41 INFO - ##### 21:47:41 INFO - ##### Running populate-webroot step. 21:47:41 INFO - ##### 21:47:41 INFO - Running pre-action listener: _resource_record_pre_action 21:47:41 INFO - Running main action method: populate_webroot 21:47:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:47:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:47:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:47:41 INFO - '--tppagecycles', 21:47:41 INFO - '7'], 21:47:41 INFO - 'tests': ['tsvgm']}}, 21:47:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:47:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:47:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:47:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:47:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:47:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['damp', 'tps']}, 21:47:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'tests': ['damp', 'tps']}, 21:47:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['dromaeo_dom']}, 21:47:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:47:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['a11yr', 21:47:41 INFO - 'ts_paint', 21:47:41 INFO - 'tpaint', 21:47:41 INFO - 'sessionrestore', 21:47:41 INFO - 'sessionrestore_no_auto_restore', 21:47:41 INFO - 'tabpaint']}, 21:47:41 INFO - 'other-e10s': {'tests': ['a11yr', 21:47:41 INFO - 'ts_paint', 21:47:41 INFO - 'tpaint', 21:47:41 INFO - 'sessionrestore', 21:47:41 INFO - 'sessionrestore_no_auto_restore', 21:47:41 INFO - 'tabpaint']}, 21:47:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:47:41 INFO - 'ts_paint', 21:47:41 INFO - 'tpaint', 21:47:41 INFO - 'sessionrestore', 21:47:41 INFO - 'sessionrestore_no_auto_restore', 21:47:41 INFO - 'tabpaint']}, 21:47:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:47:41 INFO - 'ts_paint', 21:47:41 INFO - 'tpaint', 21:47:41 INFO - 'sessionrestore', 21:47:41 INFO - 'sessionrestore_no_auto_restore', 21:47:41 INFO - 'tabpaint']}, 21:47:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['a11yr', 21:47:41 INFO - 'ts_paint', 21:47:41 INFO - 'tpaint', 21:47:41 INFO - 'sessionrestore', 21:47:41 INFO - 'sessionrestore_no_auto_restore', 21:47:41 INFO - 'tabpaint']}, 21:47:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['a11yr', 21:47:41 INFO - 'ts_paint', 21:47:41 INFO - 'tpaint', 21:47:41 INFO - 'sessionrestore', 21:47:41 INFO - 'sessionrestore_no_auto_restore', 21:47:41 INFO - 'tabpaint']}, 21:47:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['tsvgx', 21:47:41 INFO - 'tsvgr_opacity', 21:47:41 INFO - 'tart', 21:47:41 INFO - 'tscrollx', 21:47:41 INFO - 'cart']}, 21:47:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:47:41 INFO - 'tsvgr_opacity', 21:47:41 INFO - 'tart', 21:47:41 INFO - 'tscrollx', 21:47:41 INFO - 'cart']}, 21:47:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'talos_options': ['--disable-e10s'], 21:47:41 INFO - 'tests': ['tp5o']}, 21:47:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'tests': ['tp5o']}, 21:47:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'talos_options': ['--disable-e10s', 21:47:41 INFO - '--xperf_path', 21:47:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:41 INFO - 'tests': ['tp5n']}, 21:47:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:47:41 INFO - 'talos_options': ['--xperf_path', 21:47:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:47:41 INFO - 'tests': ['tp5n']}}, 21:47:41 INFO - 'talos.zip': {'path': '', 21:47:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:47:41 INFO - Running post-action listener: _resource_record_post_action 21:47:41 INFO - ##### 21:47:41 INFO - ##### Finished populate-webroot step (success) 21:47:41 INFO - ##### 21:47:41 INFO - ##### 21:47:41 INFO - ##### Running create-virtualenv step. 21:47:41 INFO - ##### 21:47:41 INFO - Running pre-action listener: _resource_record_pre_action 21:47:41 INFO - Running main action method: create_virtualenv 21:47:41 INFO - Creating virtualenv /builds/slave/test/build/venv 21:47:41 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 21:47:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:47:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:47:41 INFO - Using real prefix '/tools/python27' 21:47:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:47:41 INFO - Installing distribute.............................................................................................................................................................................................done. 21:47:44 INFO - Installing pip.................done. 21:47:44 INFO - Return code: 0 21:47:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:44 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 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:47:44 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 21:47:44 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 21:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:44 INFO - 'HOME': '/Users/cltbld', 21:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:44 INFO - 'LOGNAME': 'cltbld', 21:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:44 INFO - 'MOZ_NO_REMOTE': '1', 21:47:44 INFO - 'NO_EM_RESTART': '1', 21:47:44 INFO - 'PAGER': '/bin/cat', 21:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:44 INFO - 'PWD': '/builds/slave/test', 21:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:44 INFO - 'SHELL': '/bin/bash', 21:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:47:44 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:44 INFO - 'USER': 'cltbld', 21:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:44 INFO - 'XPC_FLAGS': '0x0', 21:47:44 INFO - 'XPC_SERVICE_NAME': '0', 21:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:44 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 21:47:44 INFO - Cleaning up... 21:47:44 INFO - Return code: 0 21:47:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:47: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')]} 21:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47: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')]} 21:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:44 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 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:47:44 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 21:47:44 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 21:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:44 INFO - 'HOME': '/Users/cltbld', 21:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:44 INFO - 'LOGNAME': 'cltbld', 21:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:44 INFO - 'MOZ_NO_REMOTE': '1', 21:47:44 INFO - 'NO_EM_RESTART': '1', 21:47:44 INFO - 'PAGER': '/bin/cat', 21:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:44 INFO - 'PWD': '/builds/slave/test', 21:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:44 INFO - 'SHELL': '/bin/bash', 21:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:47:44 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:44 INFO - 'USER': 'cltbld', 21:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:44 INFO - 'XPC_FLAGS': '0x0', 21:47:44 INFO - 'XPC_SERVICE_NAME': '0', 21:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:45 INFO - Downloading/unpacking psutil>=3.1.1 21:47:45 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 21:47:45 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 21:47:45 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 21:47:45 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 21:47:45 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 21:47:45 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 21:47:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:47:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:48 INFO - Installing collected packages: psutil 21:47:48 INFO - Running setup.py install for psutil 21:47:48 INFO - building 'psutil._psutil_osx' extension 21:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:47:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:47:48 INFO - building 'psutil._psutil_posix' extension 21:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:47:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:47:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:47:48 INFO - ^ 21:47:48 INFO - 1 warning generated. 21:47:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:47:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:47:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:47:49 INFO - Successfully installed psutil 21:47:49 INFO - Cleaning up... 21:47:49 INFO - Return code: 0 21:47:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:47:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:47:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:47:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:47:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:49 INFO - 'HOME': '/Users/cltbld', 21:47:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:49 INFO - 'LOGNAME': 'cltbld', 21:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:49 INFO - 'MOZ_NO_REMOTE': '1', 21:47:49 INFO - 'NO_EM_RESTART': '1', 21:47:49 INFO - 'PAGER': '/bin/cat', 21:47:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:49 INFO - 'PWD': '/builds/slave/test', 21:47:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:49 INFO - 'SHELL': '/bin/bash', 21:47:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:47:49 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:47:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:49 INFO - 'USER': 'cltbld', 21:47:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:49 INFO - 'XPC_FLAGS': '0x0', 21:47:49 INFO - 'XPC_SERVICE_NAME': '0', 21:47:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:47:51 INFO - Installing collected packages: mozsystemmonitor 21:47:51 INFO - Running setup.py install for mozsystemmonitor 21:47:52 INFO - Successfully installed mozsystemmonitor 21:47:52 INFO - Cleaning up... 21:47:52 INFO - Return code: 0 21:47:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:52 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 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:47:52 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 21:47:52 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 21:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:52 INFO - 'HOME': '/Users/cltbld', 21:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:52 INFO - 'LOGNAME': 'cltbld', 21:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:52 INFO - 'MOZ_NO_REMOTE': '1', 21:47:52 INFO - 'NO_EM_RESTART': '1', 21:47:52 INFO - 'PAGER': '/bin/cat', 21:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:52 INFO - 'PWD': '/builds/slave/test', 21:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:52 INFO - 'SHELL': '/bin/bash', 21:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:47:52 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:52 INFO - 'USER': 'cltbld', 21:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:52 INFO - 'XPC_FLAGS': '0x0', 21:47:52 INFO - 'XPC_SERVICE_NAME': '0', 21:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:52 INFO - Downloading/unpacking blobuploader==1.2.4 21:47:52 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 21:47:52 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 21:47:52 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 21:47:52 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 21:47:52 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 21:47:52 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 21:47:54 INFO - Downloading blobuploader-1.2.4.tar.gz 21:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:47:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47: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 21:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:47:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:47:56 INFO - Downloading docopt-0.6.1.tar.gz 21:47:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:47:56 INFO - Installing collected packages: blobuploader, requests, docopt 21:47:56 INFO - Running setup.py install for blobuploader 21:47:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:47:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:47:56 INFO - Running setup.py install for requests 21:47:56 INFO - Running setup.py install for docopt 21:47:56 INFO - Successfully installed blobuploader requests docopt 21:47:56 INFO - Cleaning up... 21:47:57 INFO - Return code: 0 21:47:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:47:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:47:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:47:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:47:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:47:57 INFO - 'HOME': '/Users/cltbld', 21:47:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:47:57 INFO - 'LOGNAME': 'cltbld', 21:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:47:57 INFO - 'MOZ_NO_REMOTE': '1', 21:47:57 INFO - 'NO_EM_RESTART': '1', 21:47:57 INFO - 'PAGER': '/bin/cat', 21:47:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:47:57 INFO - 'PWD': '/builds/slave/test', 21:47:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:47:57 INFO - 'SHELL': '/bin/bash', 21:47:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:47:57 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:47:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:47:57 INFO - 'USER': 'cltbld', 21:47:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:47:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:47:57 INFO - 'XPC_FLAGS': '0x0', 21:47:57 INFO - 'XPC_SERVICE_NAME': '0', 21:47:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-STzlCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-i11Otn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-UHiODL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-YPPJMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-OOg52V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-etzY7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:47:57 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-XLYU_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Mk1kto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-AFxu1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-rWmDW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-7UTAWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-tcmFvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-6x_BQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Wu_o22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-WbP3Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:47:58 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-pbhRad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:47:59 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-FmG2la-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:47:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:47:59 INFO - Running setup.py install for manifestparser 21:47:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:47:59 INFO - Running setup.py install for mozcrash 21:47:59 INFO - Running setup.py install for mozdebug 21:47:59 INFO - Running setup.py install for mozdevice 21:47:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:47:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:47:59 INFO - Running setup.py install for mozfile 21:47:59 INFO - Running setup.py install for mozhttpd 21:48:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Running setup.py install for mozinfo 21:48:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Running setup.py install for mozInstall 21:48:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Running setup.py install for mozleak 21:48:00 INFO - Running setup.py install for mozlog 21:48:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Running setup.py install for moznetwork 21:48:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:00 INFO - Running setup.py install for mozprocess 21:48:00 INFO - Running setup.py install for mozprofile 21:48:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:01 INFO - Running setup.py install for mozrunner 21:48:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:01 INFO - Running setup.py install for mozscreenshot 21:48:01 INFO - Running setup.py install for moztest 21:48:01 INFO - Running setup.py install for mozversion 21:48:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:48:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:48:01 INFO - Cleaning up... 21:48:01 INFO - Return code: 0 21:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:01 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')]} 21:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:01 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')]} 21:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:01 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 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:48:01 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 21:48:01 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 21:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:01 INFO - 'HOME': '/Users/cltbld', 21:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:01 INFO - 'LOGNAME': 'cltbld', 21:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:01 INFO - 'MOZ_NO_REMOTE': '1', 21:48:01 INFO - 'NO_EM_RESTART': '1', 21:48:01 INFO - 'PAGER': '/bin/cat', 21:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:01 INFO - 'PWD': '/builds/slave/test', 21:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:01 INFO - 'SHELL': '/bin/bash', 21:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:48:01 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:01 INFO - 'USER': 'cltbld', 21:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:01 INFO - 'XPC_FLAGS': '0x0', 21:48:01 INFO - 'XPC_SERVICE_NAME': '0', 21:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-TJ0e0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-1imEBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-pzjig2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-KpGUE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-TD4Dl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Ot_3u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-ZqmoML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-_VPVXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:02 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)) 21:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:02 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-slx3px-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Qkoaew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-xTRxmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-FJ34wr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-IJL0cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-52T4qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-twfWKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-hwbUhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:03 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)) 21:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:03 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-7ELGH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:03 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)) 21:48:03 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)) 21:48:03 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)) 21:48:03 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)) 21:48:03 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)) 21:48:03 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)) 21:48:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:06 INFO - Downloading blessings-1.6.tar.gz 21:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:48:06 INFO - Installing collected packages: blessings 21:48:06 INFO - Running setup.py install for blessings 21:48:06 INFO - Successfully installed blessings 21:48:06 INFO - Cleaning up... 21:48:06 INFO - Return code: 0 21:48:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:07 INFO - Reading from file tmpfile_stdout 21:48:07 INFO - Current package versions: 21:48:07 INFO - blessings == 1.6 21:48:07 INFO - blobuploader == 1.2.4 21:48:07 INFO - docopt == 0.6.1 21:48:07 INFO - manifestparser == 1.1 21:48:07 INFO - mozInstall == 1.12 21:48:07 INFO - mozcrash == 0.17 21:48:07 INFO - mozdebug == 0.1 21:48:07 INFO - mozdevice == 0.48 21:48:07 INFO - mozfile == 1.2 21:48:07 INFO - mozhttpd == 0.7 21:48:07 INFO - mozinfo == 0.9 21:48:07 INFO - mozleak == 0.1 21:48:07 INFO - mozlog == 3.2 21:48:07 INFO - moznetwork == 0.27 21:48:07 INFO - mozprocess == 0.22 21:48:07 INFO - mozprofile == 0.28 21:48:07 INFO - mozrunner == 6.11 21:48:07 INFO - mozscreenshot == 0.1 21:48:07 INFO - mozsystemmonitor == 0.3 21:48:07 INFO - moztest == 0.7 21:48:07 INFO - mozversion == 1.4 21:48:07 INFO - psutil == 3.1.1 21:48:07 INFO - requests == 1.2.3 21:48:07 INFO - wsgiref == 0.1.2 21:48:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:07 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 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:48:07 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 21:48:07 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 21:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:07 INFO - 'HOME': '/Users/cltbld', 21:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:07 INFO - 'LOGNAME': 'cltbld', 21:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:07 INFO - 'MOZ_NO_REMOTE': '1', 21:48:07 INFO - 'NO_EM_RESTART': '1', 21:48:07 INFO - 'PAGER': '/bin/cat', 21:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:07 INFO - 'PWD': '/builds/slave/test', 21:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:07 INFO - 'SHELL': '/bin/bash', 21:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:48:07 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:07 INFO - 'USER': 'cltbld', 21:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:07 INFO - 'XPC_FLAGS': '0x0', 21:48:07 INFO - 'XPC_SERVICE_NAME': '0', 21:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 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)) 21:48:07 INFO - Cleaning up... 21:48:07 INFO - Return code: 0 21:48:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:48:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:48:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:07 INFO - 'HOME': '/Users/cltbld', 21:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:07 INFO - 'LOGNAME': 'cltbld', 21:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:07 INFO - 'MOZ_NO_REMOTE': '1', 21:48:07 INFO - 'NO_EM_RESTART': '1', 21:48:07 INFO - 'PAGER': '/bin/cat', 21:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:07 INFO - 'PWD': '/builds/slave/test', 21:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:07 INFO - 'SHELL': '/bin/bash', 21:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:48:07 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:07 INFO - 'USER': 'cltbld', 21:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:07 INFO - 'XPC_FLAGS': '0x0', 21:48:07 INFO - 'XPC_SERVICE_NAME': '0', 21:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:07 INFO - Downloading/unpacking jsonschema 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:09 INFO - Downloading/unpacking functools32 (from jsonschema) 21:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:10 INFO - warning: no files found matching '*.txt' 21:48:10 INFO - no previously-included directories found matching 'build' 21:48:10 INFO - no previously-included directories found matching 'dist' 21:48:10 INFO - no previously-included directories found matching '.git*' 21:48:10 INFO - Installing collected packages: jsonschema, functools32 21:48:10 INFO - Running setup.py install for functools32 21:48:10 INFO - warning: no files found matching '*.txt' 21:48:10 INFO - no previously-included directories found matching 'build' 21:48:10 INFO - no previously-included directories found matching 'dist' 21:48:10 INFO - no previously-included directories found matching '.git*' 21:48:10 INFO - Successfully installed jsonschema functools32 21:48:10 INFO - Cleaning up... 21:48:10 INFO - Return code: 0 21:48:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11065aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110660570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc014b5900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:10 INFO - 'HOME': '/Users/cltbld', 21:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:10 INFO - 'LOGNAME': 'cltbld', 21:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:10 INFO - 'MOZ_NO_REMOTE': '1', 21:48:10 INFO - 'NO_EM_RESTART': '1', 21:48:10 INFO - 'PAGER': '/bin/cat', 21:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:10 INFO - 'PWD': '/builds/slave/test', 21:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:10 INFO - 'SHELL': '/bin/bash', 21:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:48:10 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:10 INFO - 'USER': 'cltbld', 21:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:10 INFO - 'XPC_FLAGS': '0x0', 21:48:10 INFO - 'XPC_SERVICE_NAME': '0', 21:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:11 INFO - Downloading/unpacking flake8 21:48:11 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 21:48:11 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 21:48:11 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 21:48:11 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 21:48:11 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 21:48:11 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 21:48:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:48:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:48:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:48:14 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 21:48:14 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 21:48:14 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 21:48:14 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 21:48:14 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 21:48:14 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 21:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:48:14 INFO - no previously-included directories found matching 'docs/_build' 21:48:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:48:14 INFO - Running setup.py install for pep8 21:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:48:14 INFO - no previously-included directories found matching 'docs/_build' 21:48:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:48:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:48:15 INFO - Cleaning up... 21:48:15 INFO - Return code: 0 21:48:15 INFO - Running post-action listener: _resource_record_post_action 21:48:15 INFO - Running post-action listener: _start_resource_monitoring 21:48:15 INFO - Starting resource monitoring. 21:48:15 INFO - ##### 21:48:15 INFO - ##### Finished create-virtualenv step (success) 21:48:15 INFO - ##### 21:48:15 INFO - ##### 21:48:15 INFO - ##### Running install step. 21:48:15 INFO - ##### 21:48:15 INFO - Running pre-action listener: _resource_record_pre_action 21:48:15 INFO - Running main action method: install 21:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:15 INFO - Reading from file tmpfile_stdout 21:48:15 INFO - Detecting whether we're running mozinstall >=1.0... 21:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:48:15 INFO - Reading from file tmpfile_stdout 21:48:15 INFO - Output received: 21:48:15 INFO - Usage: mozinstall [options] installer 21:48:15 INFO - Options: 21:48:15 INFO - -h, --help show this help message and exit 21:48:15 INFO - -d DEST, --destination=DEST 21:48:15 INFO - Directory to install application into. [default: 21:48:15 INFO - "/builds/slave/test"] 21:48:15 INFO - --app=APP Application being installed. [default: firefox] 21:48:15 INFO - mkdir: /builds/slave/test/build/application 21:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:48:49 INFO - Reading from file tmpfile_stdout 21:48:49 INFO - Output received: 21:48:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:48:49 INFO - Running post-action listener: _resource_record_post_action 21:48:49 INFO - ##### 21:48:49 INFO - ##### Finished install step (success) 21:48:49 INFO - ##### 21:48:49 INFO - ##### 21:48:49 INFO - ##### Running run-tests step. 21:48:49 INFO - ##### 21:48:49 INFO - Running pre-action listener: _resource_record_pre_action 21:48:49 INFO - Running main action method: run_tests 21:48:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:48:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:48:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:48:49 INFO - 2016-05-30 21:48:49.143 screenresolution[2382:36768] starting screenresolution argv=screenresolution get 21:48:49 INFO - 2016-05-30 21:48:49.146 screenresolution[2382:36768] Display 0: 1600x1200x32@60 21:48:49 INFO - 2016-05-30 21:48:49.153 screenresolution[2383:36770] starting screenresolution argv=screenresolution list 21:48:49 INFO - Available Modes on Display 0 21:48:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:48:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:48:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:48:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:48:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:48:49 INFO - Intel Iris: 21:48:49 INFO - Chipset Model: Intel Iris 21:48:49 INFO - Type: GPU 21:48:49 INFO - Bus: Built-In 21:48:49 INFO - VRAM (Dynamic, Max): 1536 MB 21:48:49 INFO - Vendor: Intel (0x8086) 21:48:49 INFO - Device ID: 0x0a2e 21:48:49 INFO - Revision ID: 0x0009 21:48:49 INFO - Displays: 21:48:49 INFO - Display: 21:48:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:48:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:48:49 INFO - Display Serial Number: 1600x1200 60 21:48:49 INFO - Main Display: Yes 21:48:49 INFO - Mirror: Off 21:48:49 INFO - Online: Yes 21:48:49 INFO - Rotation: Supported 21:48:49 INFO - Return code: 0 21:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:48:49 INFO - Python 2.7.3 21:48:49 INFO - Return code: 0 21:48:49 INFO - grabbing minidump binary from tooltool 21:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:48:49 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/macosx64/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 0x1104fed30>, '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 0x110322db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1104f5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:48:49 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:48:49 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 21:48:49 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:48:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:48:49 INFO - Return code: 0 21:48:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:48:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:48:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:48:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:48:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:48:50 INFO - Return code: 0 21:48:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 21:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0237 --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 21:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bdPhAZHaXz/Render', 21:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:50 INFO - 'HOME': '/Users/cltbld', 21:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:50 INFO - 'LOGNAME': 'cltbld', 21:48:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:48:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:50 INFO - 'MOZ_NO_REMOTE': '1', 21:48:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:48:50 INFO - 'NO_EM_RESTART': '1', 21:48:50 INFO - 'PAGER': '/bin/cat', 21:48:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:50 INFO - 'PWD': '/builds/slave/test', 21:48:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:50 INFO - 'SHELL': '/bin/bash', 21:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners', 21:48:50 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:50 INFO - 'USER': 'cltbld', 21:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:50 INFO - 'XPC_FLAGS': '0x0', 21:48:50 INFO - 'XPC_SERVICE_NAME': '0', 21:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 21:48:50 INFO - mozversion application_buildid: 20160530205547 21:48:50 INFO - mozversion application_changeset: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 21:48:50 INFO - mozversion application_display_name: Nightly 21:48:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:48:50 INFO - mozversion application_name: Firefox 21:48:50 INFO - mozversion application_remotingname: firefox 21:48:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:48:50 INFO - mozversion application_vendor: Mozilla 21:48:50 INFO - mozversion application_version: 49.0a1 21:48:50 INFO - mozversion platform_buildid: 20160530205547 21:48:50 INFO - mozversion platform_changeset: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 21:48:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:48:50 INFO - mozversion platform_version: 49.0a1 21:48:50 INFO - using testdate: 1464670130 21:48:50 INFO - actual date: 1464670130 21:48:50 INFO - starting webserver on 'localhost:54437' 21:48:50 INFO - SUITE-START | Running 2 tests 21:48:50 INFO - TEST-START | dromaeo_css 21:48:50 INFO - operating with platform_type : mac_ 21:48:50 INFO - Initialising browser for dromaeo_css test... 21:48:50 INFO - TEST-INFO | started process 2401 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpsCuNLO/profile http://localhost:54437/getInfo.html) 21:48:53 INFO - PROCESS | 2401 | __metrics Screen width/height:1600/1200 21:48:53 INFO - PROCESS | 2401 | colorDepth:24 21:48:53 INFO - PROCESS | 2401 | Browser inner width/height: 1024/689 21:48:53 INFO - PROCESS | 2401 | __metrics 21:48:53 INFO - PROCESS | 2401 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:48:53 ERROR - PROCESS | 2401 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:48:53 INFO - TEST-INFO | 2401: exit 0 21:48:53 INFO - Browser initialized. 21:48:53 INFO - Running cycle 1/1 for dromaeo_css test... 21:48:53 INFO - TEST-INFO | started process 2403 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpsCuNLO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:05 INFO - PROCESS | 2403 | RSS: Main: 241782784 21:49:05 INFO - PROCESS | 2403 | 21:49:57 INFO - PROCESS | 2403 | 1464670197297 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] 21:52:07 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:54437/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54437/tests/dromaeo/cssquery-ext.html) 21:52:08 INFO - PROCESS | 2403 | RSS: Main: 380866560 21:52:08 INFO - PROCESS | 2403 | 21:55:11 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:54437/tests/dromaeo/cssquery-ext.html (next: http://localhost:54437/tests/dromaeo/cssquery-jquery.html) 21:55:12 INFO - PROCESS | 2403 | RSS: Main: 376254464 21:55:12 INFO - PROCESS | 2403 | 21:58:15 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:54437/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54437/tests/dromaeo/cssquery-mootools.html) 21:58:15 INFO - PROCESS | 2403 | RSS: Main: 372142080 21:58:15 INFO - PROCESS | 2403 | 22:01:17 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:54437/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54437/tests/dromaeo/cssquery-prototype.html) 22:01:18 INFO - PROCESS | 2403 | RSS: Main: 367824896 22:01:18 INFO - PROCESS | 2403 | 22:04:21 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:54437/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54437/tests/dromaeo/cssquery-yui.html) 22:04:22 INFO - PROCESS | 2403 | RSS: Main: 334798848 22:04:22 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | Cycle 1(1): loaded http://localhost:54437/tests/dromaeo/cssquery-yui.html (next: http://localhost:54437/tests/dromaeo/cssquery-dojo.html) 22:07:25 INFO - PROCESS | 2403 | RSS: Main: 381624320 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | __start_tp_report 22:07:25 INFO - PROCESS | 2403 | _x_x_mozilla_page_load 22:07:25 INFO - PROCESS | 2403 | _x_x_mozilla_page_load_details 22:07:25 INFO - PROCESS | 2403 | |i|pagename|runs| 22:07:25 INFO - PROCESS | 2403 | |0;dojo.html;3398.932021359573;3408.778429402087;3424.6404127566607;3458.8097654628987;3472.0972547137753;5560.555155587544;5621;5636;5720.941625799225;5770.596058275923;5621.943780562193;5682.318121825387;5722.885542289152;5757.42425757424;5786.508146807516;5060.645754797166;5073.644844860862;5144.228365745131;5144.485551444863;5242.056429842627;5087.694738315695;5232.659877107995;5260;5266.7366631668265;5276.577873770108;4496;4983.953369792348;5068.113080210948;5114.28400023997;5126.794928202868;5081.034603425337;5081.5426611605135;5111.284420181178;5136.871578210537;5173.870653233662;5057.418396884332;5104.387473503166;5132.358455193101;5165.9225111623355;5191.480851914816;5495.065838807413;5621.915671264935;5702.686352250625;5719;5729.799457018984;6099.115628233925;6145.279321789906;6145.446909778141;6188.009918413078;6206.937930620681;5480.150576660624;6229;6293.559450838443;6301.212348456443;6306.936930630727;5614.989301925692;5659.830205093854;5791.276090488646;5803.5357171426185;5817.0692689169955;6197.4732147767945;6275.152854364694;6287.088372186007;6294.244690637152;6312.558120931582;6099.4815440688035;6105.700080128636;6122.908156377658;6127.662978536223;6140.17107690465;6061.818145455643;6105.664188469676;6228.532860035515;6252.718627661766;6267.561270711007;5754.856128596735;6046.395360463918;6100.694965251809;6102.72537735803;6117.113018612276;6085.421884920926;6159;6178.567500274938;6181.320054793969;6188.2883468400805;4841.176999910023;4846.975765121222;4856.8300109495995;4859.562639362474;4874.147024270738;4400.339949007611;4423.20382331177;4462.6876118672035;4468.017036252084;4475.26158183896;5643.238162848045;5648.6893220872425;5649.0396632572565;5664;5678.801241956594;6139.124180917401;6203.441690247809;6210.627362358273;6218.720157592919;6238.532110091761;4738.47876733559;5594.496495315441;5682.488576028178;5682.857928551819;5706.600537962303;5369.624126311121;5382.91925621116;5387.515123638891;5395.622306438511;5438.673679579238;6244.625322480666;6253.749850005949;6259.749610015549;6286.968565157145;6327.905081423874;6303;6307.400796924285;6314.810555683337;6324.557280990287;6325.905111423241;6211.13044173807;6307.842303942438;6330.89209388346;6346.238451385863;6356.841078973062;6066.181065556093;6073.605215660968;6129.02260737745;6212;6215.9378406217165;5185.948140518546;6245.406686364788;6267.6866156692895;6283.02613094971;6285.874282514233;5859.2382990211;5947.434993675594;6066.575339726177;6079.088136779342;6100.633961962297;5977.7310021050025;6066.636001839904;6077.544184186116;6097.359777223328;6130.448259656652;5319.760610772447;5848.736806843617;5870.735816888165;5907.113932910098;5946.881062378641;5791.449812267828;5976.103584462366;5989.131575921379;6063.587664112524;6064.432105700125;5948.791792287249;5952.464278214981;5952.523798096228;5961.926853166298;5964.373740757158 22:07:25 INFO - PROCESS | 2403 | |1;ext.html;515.2503107977891;517.0976645753159;526.9367675878896;543.6656456279389;560.8569814697252;22835.200767973125;23040.308790736282;25522.106726264585;26477.735222647767;26506.734932650666;43402.56597434032;44022.11955760883;44179.77910110453;46648.30027549581;46851.82870428241;9447.858282125773;9634.566444510014;9653.613855445808;9670.419774813534;9696.17582505488;7579.81050473737;8551.572421378938;9585.137337639611;9740.220782337432;9765.925748167696;8887.422317549372;8954.2836573074;8968.327375446836;11437.828432573519;11713.53145874164;11497.252678575866;11599.710007249801;11606.129540284512;11610.767784644344;11644.825327620134;11501.539938402453;11626.186166968315;11628.88371116295;11639.941800290944;11647;31450.842745786125;32013.6798632013;32113;33493.83253083719;39404.423823047044;3188.756385009848;3533.3639944809925;3979.1643754811525;4184.4351012613215;4218.535961044283;31521.21196970094;31779.205519861956;31889.202769930704;31942.722291108326;32255.677443225737;3196.280836811722;3331.716804071657;3358.429067058606;3364.0076177527444;3403.931921361559;351802;352896;353264;353806;354310.99999999744;57110.714446427504;58160.70919645375;58172.70913645405;58238;58329.83340333169;60827.69586152129;61044.694776526725;61114.777704446555;61275.69362153161;61283.69358153181;16342;16603.41888033913;16608.335666573203;16612.750808737877;16621.916890415472;12980.610581682564;13048.608541743763;13050.15174013686;13057.28184949837;13107.475700971854;13487.325633718276;14308.427662893577;15477.148756818258;15482.071075735266;15487.845121548866;6053.27360716708;6205.379462053758;6954.965225173842;7103.89344159838;7200.279972002862;907.3875134284332;959.4962644611495;1035.5547114740734;1092.4865313302735;1116.2688439072258;26745.46509069808;28925.119867208985;29518.55722164169;29987.250318741768;30030.099097027123;985.2167526816198;1127.9266847654876;1166.1312322319925;1199.5141967503175;1205.813098969661;15214.847851521343;15216.619584510476;15450.918435709393;15732.134732589817;15862.920685396499;667834.6608266927;672804;673924;675673;678393;43156.56843431525;50879.74560127176;57805.71097144572;59002.70498647479;59018.70490647605;1234.370471059766;1267.4866678995022;1448.6161167290677;1472.6244807574215;1480.2450750117303;30017.187694607048;30222.697773021988;30512.237194070327;30746.692533074383;30929.69070309358;50205.74897125491;50491.747541262055;50575.49424505708;50582.49417505925;50658.98682026412;831697;850019;850372.7481362553;851083;856569;39600.603993960845;40828.59171408248;41591.58408415877;41918;42820.78589607032;47305.54738777962;47464.28803568018;48080.27879581879;48158.759206203744;48921.510784891696;39445.60554394419;39907;40291.395629065;40788;40999.1800164001;38392.6160738389;38668;38754.0311492215;39157.60842391539;39528;38494.037649059;38642;38888;38943.026424339616;39136.2172756549;33428;34767.65232347745;34876.9536913893;35057.47413788744;35066.64933350736;20359.69460458124;22662.093516259163;24956.750432495443;25073.99704011892;25300 22:07:25 INFO - PROCESS | 2403 | |2;jquery.html;369.31492082187543;380.969522438205;381.81291167328004;384.85183204466273;408.979551022449;14897.925510372434;15975.600609984727;19051.333203337887;21615.135394584217;21644.783552164474;20491;20652.69020964683;21076.578468430624;21140.682889756627;21264.936753162317;5099.6430249882615;5471.206675032118;5472.616916815825;5520.365158006821;5537.947789919902;3425.5033020212177;4141.979290103561;4154.584541545852;4158.604932531404;4952.801887924478;4286;6191.504679625642;6192.659403732793;6214.098955651428;6228.626282423046;4675.298705194214;6060.151578778997;6092.7258273377365;6123.265208175003;6202.069689546559;5652.943470565283;5900.114982752579;5901.055831067051;5911.201987731644;5922.911156332659;13375.598732037957;13737;20585.8970705147;26314.6052809208;26838.5974210387;2183.748868880067;2226.9665955010687;2307.896144673477;2781.8469984150865;3061.755059595227;9374.156325930699;9973.65092221776;10212.591496340137;10293.330933489375;10342.276040677156;1780.6705759434417;1830.8993005384698;1843.3916807453643;1875.812418758127;1935.1001784170428;103604.96395036028;127235;132901.99999999904;133443.998340024;134710;11489.54041838317;11747.588834390926;11750.58872939443;11754.943714634257;11955.462004209832;13014.15407998477;13739;13741.312934353242;14981.925090374478;15014.399424022917;7417.592032438268;7913.287804097544;8030.5181689098845;8202.138775428613;8561.700340488052;8474.703385381483;8528.360372972053;8620.95689521561;8638.870416943751;8657.307415406753;4463.66522510813;4552.385427967249;4562.58936695699;4567.1779079765965;4596.88507787308;1579.5103517909486;1778.5642517583276;1823.215345066229;1830.5240637434363;1890.9054547272856;881.6605606841413;913.5477938420524;932.3287233192091;972.9270304727174;996.9750756231007;19857.801421985885;20543.79456205449;20566.07452664634;20676.483087922923;20836.853973031324;936.3866667332908;941.7363138321281;950.1068995144542;983.8327484327682;994.8358520844124;10388.792224155475;10647.84028239577;11129.942655447881;11169.888301117047;11175.608853690244;587147.0564709626;592630;595847.0207648934;605237;606012;122863.3856830728;123220.38389807993;124167;125819;126293;971.8493633486821;983.0759086458849;1001.2940876681922;1004.2819384140229;1005.4017859373661;17652.558686032437;18289.359872404402;18370.448886533424;18730.157142928874;18827.623447531245;52140.478595213564;52819;53275.733621331645;53810;54249.91500169896;77796;78948.60525697334;79014.20985790298;79314.20685793068;80449.79325310243;8080.434369594307;8156.306713929145;8205.384596155433;8215;8391.412601117863;12299.446524906221;12599.370031498573;12679.175853569488;12816.487340506275;12888.742225155633;10920.672379828618;10942.23404361687;11065.391403472886;11156.553737850396;11178.608748694081;9577.952110239405;10093;10455.268131231045;11002.889971100187;11046;10878.347299162075;11130.888691112985;11185.706930079152;11322.490487928228;11398.08815294791;7986.84026319482;8145.144759800136;8234.013007383068;8378.413511054168;8474.830503389774;10354.275200735876;11256.099512039182;11442.37066961325;11464.025557827345;11498.367589782642 22:07:25 INFO - PROCESS | 2403 | |3;mootools.html;1386.6533366658336;1535.8924875258733;1579.060459026879;1793.4529968359652;1941.310834653697;7661.387089032878;7820.217978202177;8515.53164575948;8771.649134034647;8780.517071561062;8522.744317670464;8760.780980475476;8764;8767.254783343424;8785.868211976825;2346.2843832631042;2369.8815059247054;2375.4655202579456;2376.738558758535;2377.9286621401384;2343.3087239264373;2366.514864452783;2394.7006624171977;2394.8084153267696;2395.8682272475007;2342.8594284342907;2365.5860224460735;2370.905163793455;2383.332666853284;2386.5107652931106;2326.2904814031713;2357.528494301138;2361.622140457527;2364.385259832456;2374.750651181618;2247.4156719252946;2331.8367714260007;2337.146941366406;2338.1465764995824;2344.6717459555675;7867.645955931939;8142.7557173284895;8803.427777194525;8882.289416846637;8884.511351875644;5143.897122057575;5324.387695415038;5330.9733451332495;5390.1914712793005;5390.649607775521;3255.983720081408;3264.7061764441078;3550.751447398683;3576.481410195533;3628.2380700053022;1071.8660167479065;1072.3351522056298;1076.3272954403499;1086.190787863047;1091.1489038549944;11639.418029098511;11757.764844703059;11766.176367654267;11788.233764805345;11830.645080647595;4614.307853821886;4722.834700785456;4861.173600487926;4863;4866.975665121722;10224.335418197794;10242.436665983443;10255.28213025096;10262.332948358333;10270.897291026995;5294.867628309246;5382.703951282717;5388;5390.784368625289;5393.164059570773;4407.845725399596;4780.545848144421;4902.975485122552;4909.4845041270155;4926.359573255454;3795.772253664789;3829.923401531954;3888.941665875014;3918.5689574146822;3937.114149316381;1070.641335152717;1180.9704757381135;1249.28790589365;1251.355551890777;1255.6546949588935;758.4425447296233;883.8188171424842;940.6924375118591;955.9330846840654;962.0860182826343;6117.571769976148;6160.322364539897;6179.721912513947;6249.843753906098;6316.494680425555;925.6297481007516;925.8333499969935;971.7473456901257;1000.8148492528906;1015.2537884654773;4050.7772072536295;4094.488188976378;4123.60825721556;4160.45914031174;4166.395872598457;10418.479076046167;10729.678109656723;10812.459377031117;10813.513391897384;10875.401852898009;10356.585736570603;10685.732856678489;10775;10782.67651970442;10830.729231769268;8069.273765361028;8085.757427277191;8183.263506284463;8239.340852731768;8242.392733417046;5504.146857237135;5878.588498805043;5952.077427998667;6065.332813390612;6066.241719785027;9446.433214007182;9924.206063515046;10200.540975655966;10203.183745300506;10219.23355748307;10202.33684810485;10254.487275636338;10341.482925853526;10453.268271221246;10481.056704896597;7427.740029098955;7553.584552849646;7573.848523029619;7587.620618968919;7594.31651151399;8176.141505141874;8207.179282071746;8309.95845020771;8310.21052999964;8320.542370169456;6550.6069635822005;7250.202477727551;7354.595497247703;7483.738069167553;7638.197989211053;7221;7282.854342913006;7309.671064801991;7443.069616297963;7471.551706897603;6985.860282794214;7037.331453511908;7067.469939754642;7075.646217689198;7092.787216383516;4100.323446631272;4878.682885612424;5075.883305672746;5112.642115051911;5120.71836049006;5075.746212689424;5081.0854046272025;5139.383274007143;5160.432352441304;5245.632805703717 22:07:25 INFO - PROCESS | 2403 | |4;prototype.html;305.9021113243763;308.6805156662854;311.9516474946384;316.7450202586918;319.85926192475307;4254.085371645096;5308.681479111256;5334.51989320961;5340.279062326585;5353.62524623276;4645.790939407726;5227.686338819664;5244.606654500909;5270.942853145176;5296.9735151324285;3613.7351926825663;3962.32640451124;3980.9402858956996;3992.361222204448;4005.319095753729;3658.762180458275;4000.319785743559;4024.4969378827645;4045.7167998240134;4062.7608579383277;3964.623360780721;4013.2575473537345;4021.3163762160357;4047.3119569673227;4056.9797151014204;3553.591336996227;3730.664240218366;3831.3103641344546;4033.7176397652174;4055.026793569522;3192.776505644606;3489.4068008438626;3506.8246587670515;3520.8239588020497;3542.4509201073874;4211.599898009715;4658.091672123937;5060.822871199527;5066.113430149709;5100.38795344561;3924.8029351047917;4424.455937835757;4535.9546404536195;4578.175928332898;4617.492075871652;4720.952790472085;5437.102878025121;5517.531009864167;5544.782087165167;5552.583556233259;3310.8344582771006;3385.5937287525653;3854.5374555053295;3904;3904.39481880309;4643.6285097192485;5110.923336149961;5707.603975634533;5779.3642699303045;5913.615614985012;3366.8316584170693;3964.960350396517;3968.8015599219925;3973.7417067890497;3978.4827972363987;4006.198760247904;4025.77858217795;4028.2749105160765;4052.49343832021;4056.79716014204;3905.7851818150275;4192.958070419318;4375.47494300686;4426.424564806554;4620;5090.720010399464;5138.4604616514725;5219.765110570033;5537.141743029837;5595.804146854841;1229.883161099694;1330.2750001249342;1339.2500199888093;1358.280111540885;1376.7315373502272;775.5424299663226;799.4923223752843;842.4692443760514;865.8311629232365;888.3870129610548;4591.150637132141;4698;4734.934639706039;5000.474855169208;5189.974050129726;4815.6147508199965;5318.202269659505;5406.729663516809;5700.657960522382;5710.314762228559;4423.699676168374;4702.247640377524;5130.7947682093045;5144.382674079135;5191.974040129775;2882.1641723899834;2973.895913643012;3006.1733023418733;3029.606381064691;3032.9848350758107;5145.76844042019;5261.657992230493;5895.55783316253;5981.906332783491;6005.669688167105;4864.610831133501;5112.590992720573;5838.4745372915795;5870.354261031283;5927.792527261525;4878.56092951636;5552.805651802249;5574;5661.830145095653;5992.093834490269;4874.707517548958;5453.7818487260065;5481.232627432083;5704.828855134353;5761.279840019997;5614.185943038155;5632.493075623213;5641.489537155926;5649.971750141223;5664.518515926193;4774.40319960005;5521.365043020104;5937.554683398676;5949.91075133882;5985.6408615483215;3379.5606571145595;3394.9151271218416;3639.9626106559504;3882.1071153634257;3946.145311703495;4651.441826980784;5357;5644.153376993319;5676.545876329967;5682.698507104629;4681.742504162304;4829.73436460998;5167.6641018333685;5580;5592.182540674378;5130.358705161855;5211.342215470062;5253.579713622978;5531.502164805187;5566.721663916707;4692.92960605598;4920.991196804718;5084.2627818966785;5341.679499229903;5415.458454154553;5024;5407.242985982044;5421.2681288025615;5576.665400076008;5587.217789509553;4537.387452693844;5424.376196737426;5445.047116754631;5446.930166536849;5463.617546771688 22:07:25 INFO - PROCESS | 2403 | |5;yui.html;701.8491024429746;759.8594260061889;771.2441807029115;821.0024819843885;837.1461109668137;14738.77891831622;14921.403143874246;15211.619709507268;15225.923870380633;15251.92374038131;13874.791878121836;13888.86111138891;13922.651933701662;13932.651683707913;13951.720965580682;715.7459102018761;749.2806905370824;874.7506960516254;900.0639335091513;911.8905731312219;897.9820403591924;911.0843602179833;914.4604683236889;916.5554705967601;918.4030380252856;977.3891317926297;981.1709105805583;981.4062492192228;981.7202097402242;982.6216906491017;753.4198667026386;787.1420152034253;788.86589279822;789.3685051958411;972.9805403891918;687.8383579858729;723.0022568855;724.0551080839502;724.7354715528846;725.3942957630385;14346.282685865664;14431.711365772626;14444.783328250085;14463;14464.49374271906;13170.078094533386;13346.666333341647;13401.932990335083;13656.726865462635;13686.931565342109;11727.53089876404;12055.21641093326;12190.268583884907;12319.199252048587;12625.116241863074;4404.11917616478;4481.753503557303;4511.977440112812;4554.612857907082;4571.519990401027;81853.18146818515;82475;82619;84731.57634211851;85708;10678.145748340114;10685.167919700054;10792.78414431707;10836.837447438294;10894.237403381687;21109.050092745863;21152.259670911444;21163.365099047052;21167.470813229484;21193.894030530057;7564.773056808304;7681.6543255552515;7841.960790196013;7883.6846526138315;7995.960020199862;4411;4428.933565996513;4467.24056910326;4481.619062379734;4487.394201782718;3283.4089863824265;3406.386850366958;3408.9659103409144;3409.284050349405;3411.8976430707116;580.9078931608633;588.2852334413708;650.6876699184418;654.2835595023441;659.8746238214724;543.8341305901738;590.3358721438382;618.9597676151036;630.3224034163201;638.2755572425331;8228.465149765247;8617.655293788304;8644.956775216084;8731.563421828883;8959.462432254086;516.6848222584221;608.4797498139116;619.615838180331;625.7809766581751;649.4998850884792;3572.4462708280257;4142.440770496005;4144.023771300943;4149.813258403379;4168.8332466700995;91690;92349.53825230831;93710.57884231537;94898.52550737202;95637;80041;80121;81021.59489202517;81254;82448.58775706083;52492.73753631131;68153.93307301184;69900.65049674718;70235.2976470239;73213.53572928619;8068.573928781159;8071.43499955021;8080.313173380328;8181.877271840808;8229.83540329202;47031.76484117557;47186.056278874916;47272.2909156356;48203;48214.794630133125;62326.68836655788;65137.674311628136;65179.02231466627;65754;66262;10067.69796906094;10568.735781605521;10733.570657173626;10987.340759554452;11037.668869933916;10270.948645256725;11685.240459370114;11980.46087926028;12266.282720419327;12318.445669945053;9740.610375584898;10052.597896084075;10138.138258248131;10155.898441015495;11240;10621.468926553487;10908.78182436331;11006.337612915131;11113.699791308223;11254.268472549258;10075.84886226722;10997.175211858983;11240.381779002233;11270.458234671978;11331.830022549504;8699.173578510032;9076.27389808827;9132.908670913206;9306.208972237435;9518.61925522999;9133.360664753403;9154.771130721785;9235.445873247358;9349.85693063869;9429.387089839138 22:07:25 INFO - PROCESS | 2403 | __end_tp_report 22:07:25 INFO - PROCESS | 2403 | __start_cc_report 22:07:25 INFO - PROCESS | 2403 | _x_x_mozilla_cycle_collect,1388 22:07:25 INFO - PROCESS | 2403 | __end_cc_report 22:07:25 INFO - PROCESS | 2403 | __startTimestamp1464671245774__endTimestamp 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | ------- Summary: start ------- 22:07:25 INFO - PROCESS | 2403 | Number of tests: 6 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | [#0] dojo.html Cycles:165 Average:5688.55 Median:5870.74 stddev:640.29 (10.9%) stddev-sans-first:616.54 22:07:25 INFO - PROCESS | 2403 | Values: 3398.9 3408.8 3424.6 3458.8 3472.1 5560.6 5621.0 5636.0 5720.9 5770.6 5621.9 5682.3 5722.9 5757.4 5786.5 5060.6 5073.6 5144.2 5144.5 5242.1 5087.7 5232.7 5260.0 5266.7 5276.6 4496.0 4984.0 5068.1 5114.3 5126.8 5081.0 5081.5 5111.3 5136.9 5173.9 5057.4 5104.4 5132.4 5165.9 5191.5 5495.1 5621.9 5702.7 5719.0 5729.8 6099.1 6145.3 6145.4 6188.0 6206.9 5480.2 6229.0 6293.6 6301.2 6306.9 5615.0 5659.8 5791.3 5803.5 5817.1 6197.5 6275.2 6287.1 6294.2 6312.6 6099.5 6105.7 6122.9 6127.7 6140.2 6061.8 6105.7 6228.5 6252.7 6267.6 5754.9 6046.4 6100.7 6102.7 6117.1 6085.4 6159.0 6178.6 6181.3 6188.3 4841.2 4847.0 4856.8 4859.6 4874.1 4400.3 4423.2 4462.7 4468.0 4475.3 5643.2 5648.7 5649.0 5664.0 5678.8 6139.1 6203.4 6210.6 6218.7 6238.5 4738.5 5594.5 5682.5 5682.9 5706.6 5369.6 5382.9 5387.5 5395.6 5438.7 6244.6 6253.7 6259.7 6287.0 6327.9 6303.0 6307.4 6314.8 6324.6 6325.9 6211.1 6307.8 6330.9 6346.2 6356.8 6066.2 6073.6 6129.0 6212.0 6215.9 5185.9 6245.4 6267.7 6283.0 6285.9 5859.2 5947.4 6066.6 6079.1 6100.6 5977.7 6066.6 6077.5 6097.4 6130.4 5319.8 5848.7 5870.7 5907.1 5946.9 5791.4 5976.1 5989.1 6063.6 6064.4 5948.8 5952.5 5952.5 5961.9 5964.4 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | [#1] ext.html Cycles:180 Average:74690.70 Median:38443.33 stddev:177511.54 (461.7%) stddev-sans-first:177922.14 22:07:25 INFO - PROCESS | 2403 | Values: 515.3 517.1 526.9 543.7 560.9 22835.2 23040.3 25522.1 26477.7 26506.7 43402.6 44022.1 44179.8 46648.3 46851.8 9447.9 9634.6 9653.6 9670.4 9696.2 7579.8 8551.6 9585.1 9740.2 9765.9 8887.4 8954.3 8968.3 11437.8 11713.5 11497.3 11599.7 11606.1 11610.8 11644.8 11501.5 11626.2 11628.9 11639.9 11647.0 31450.8 32013.7 32113.0 33493.8 39404.4 3188.8 3533.4 3979.2 4184.4 4218.5 31521.2 31779.2 31889.2 31942.7 32255.7 3196.3 3331.7 3358.4 3364.0 3403.9 351802.0 352896.0 353264.0 353806.0 354311.0 57110.7 58160.7 58172.7 58238.0 58329.8 60827.7 61044.7 61114.8 61275.7 61283.7 16342.0 16603.4 16608.3 16612.8 16621.9 12980.6 13048.6 13050.2 13057.3 13107.5 13487.3 14308.4 15477.1 15482.1 15487.8 6053.3 6205.4 6955.0 7103.9 7200.3 907.4 959.5 1035.6 1092.5 1116.3 26745.5 28925.1 29518.6 29987.3 30030.1 985.2 1127.9 1166.1 1199.5 1205.8 15214.8 15216.6 15450.9 15732.1 15862.9 667834.7 672804.0 673924.0 675673.0 678393.0 43156.6 50879.7 57805.7 59002.7 59018.7 1234.4 1267.5 1448.6 1472.6 1480.2 30017.2 30222.7 30512.2 30746.7 30929.7 50205.7 50491.7 50575.5 50582.5 50659.0 831697.0 850019.0 850372.7 851083.0 856569.0 39600.6 40828.6 41591.6 41918.0 42820.8 47305.5 47464.3 48080.3 48158.8 48921.5 39445.6 39907.0 40291.4 40788.0 40999.2 38392.6 38668.0 38754.0 39157.6 39528.0 38494.0 38642.0 38888.0 38943.0 39136.2 33428.0 34767.7 34877.0 35057.5 35066.6 20359.7 22662.1 24956.8 25074.0 25300.0 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | [#2] jquery.html Cycles:180 Average:34906.59 Median:14311.25 stddev:99915.49 (698.2%) stddev-sans-first:100162.12 22:07:25 INFO - PROCESS | 2403 | Values: 369.3 381.0 381.8 384.9 409.0 14897.9 15975.6 19051.3 21615.1 21644.8 20491.0 20652.7 21076.6 21140.7 21264.9 5099.6 5471.2 5472.6 5520.4 5537.9 3425.5 4142.0 4154.6 4158.6 4952.8 4286.0 6191.5 6192.7 6214.1 6228.6 4675.3 6060.2 6092.7 6123.3 6202.1 5652.9 5900.1 5901.1 5911.2 5922.9 13375.6 13737.0 20585.9 26314.6 26838.6 2183.7 2227.0 2307.9 2781.8 3061.8 9374.2 9973.7 10212.6 10293.3 10342.3 1780.7 1830.9 1843.4 1875.8 1935.1 103605.0 127235.0 132902.0 133444.0 134710.0 11489.5 11747.6 11750.6 11754.9 11955.5 13014.2 13739.0 13741.3 14981.9 15014.4 7417.6 7913.3 8030.5 8202.1 8561.7 8474.7 8528.4 8621.0 8638.9 8657.3 4463.7 4552.4 4562.6 4567.2 4596.9 1579.5 1778.6 1823.2 1830.5 1890.9 881.7 913.5 932.3 972.9 997.0 19857.8 20543.8 20566.1 20676.5 20836.9 936.4 941.7 950.1 983.8 994.8 10388.8 10647.8 11129.9 11169.9 11175.6 587147.1 592630.0 595847.0 605237.0 606012.0 122863.4 123220.4 124167.0 125819.0 126293.0 971.8 983.1 1001.3 1004.3 1005.4 17652.6 18289.4 18370.4 18730.2 18827.6 52140.5 52819.0 53275.7 53810.0 54249.9 77796.0 78948.6 79014.2 79314.2 80449.8 8080.4 8156.3 8205.4 8215.0 8391.4 12299.4 12599.4 12679.2 12816.5 12888.7 10920.7 10942.2 11065.4 11156.6 11178.6 9578.0 10093.0 10455.3 11002.9 11046.0 10878.3 11130.9 11185.7 11322.5 11398.1 7986.8 8145.1 8234.0 8378.4 8474.8 10354.3 11256.1 11442.4 11464.0 11498.4 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | [#3] mootools.html Cycles:180 Average:5731.59 Median:4862.09 stddev:3210.18 (66.0%) stddev-sans-first:3202.58 22:07:25 INFO - PROCESS | 2403 | Values: 1386.7 1535.9 1579.1 1793.5 1941.3 7661.4 7820.2 8515.5 8771.6 8780.5 8522.7 8760.8 8764.0 8767.3 8785.9 2346.3 2369.9 2375.5 2376.7 2377.9 2343.3 2366.5 2394.7 2394.8 2395.9 2342.9 2365.6 2370.9 2383.3 2386.5 2326.3 2357.5 2361.6 2364.4 2374.8 2247.4 2331.8 2337.1 2338.1 2344.7 7867.6 8142.8 8803.4 8882.3 8884.5 5143.9 5324.4 5331.0 5390.2 5390.6 3256.0 3264.7 3550.8 3576.5 3628.2 1071.9 1072.3 1076.3 1086.2 1091.1 11639.4 11757.8 11766.2 11788.2 11830.6 4614.3 4722.8 4861.2 4863.0 4867.0 10224.3 10242.4 10255.3 10262.3 10270.9 5294.9 5382.7 5388.0 5390.8 5393.2 4407.8 4780.5 4903.0 4909.5 4926.4 3795.8 3829.9 3888.9 3918.6 3937.1 1070.6 1181.0 1249.3 1251.4 1255.7 758.4 883.8 940.7 955.9 962.1 6117.6 6160.3 6179.7 6249.8 6316.5 925.6 925.8 971.7 1000.8 1015.3 4050.8 4094.5 4123.6 4160.5 4166.4 10418.5 10729.7 10812.5 10813.5 10875.4 10356.6 10685.7 10775.0 10782.7 10830.7 8069.3 8085.8 8183.3 8239.3 8242.4 5504.1 5878.6 5952.1 6065.3 6066.2 9446.4 9924.2 10200.5 10203.2 10219.2 10202.3 10254.5 10341.5 10453.3 10481.1 7427.7 7553.6 7573.8 7587.6 7594.3 8176.1 8207.2 8310.0 8310.2 8320.5 6550.6 7250.2 7354.6 7483.7 7638.2 7221.0 7282.9 7309.7 7443.1 7471.6 6985.9 7037.3 7067.5 7075.6 7092.8 4100.3 4878.7 5075.9 5112.6 5120.7 5075.7 5081.1 5139.4 5160.4 5245.6 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | [#4] prototype.html Cycles:180 Average:4444.59 Median:4899.78 stddev:1351.48 (27.6%) stddev-sans-first:1319.09 22:07:25 INFO - PROCESS | 2403 | Values: 305.9 308.7 312.0 316.7 319.9 4254.1 5308.7 5334.5 5340.3 5353.6 4645.8 5227.7 5244.6 5270.9 5297.0 3613.7 3962.3 3980.9 3992.4 4005.3 3658.8 4000.3 4024.5 4045.7 4062.8 3964.6 4013.3 4021.3 4047.3 4057.0 3553.6 3730.7 3831.3 4033.7 4055.0 3192.8 3489.4 3506.8 3520.8 3542.5 4211.6 4658.1 5060.8 5066.1 5100.4 3924.8 4424.5 4536.0 4578.2 4617.5 4721.0 5437.1 5517.5 5544.8 5552.6 3310.8 3385.6 3854.5 3904.0 3904.4 4643.6 5110.9 5707.6 5779.4 5913.6 3366.8 3965.0 3968.8 3973.7 3978.5 4006.2 4025.8 4028.3 4052.5 4056.8 3905.8 4193.0 4375.5 4426.4 4620.0 5090.7 5138.5 5219.8 5537.1 5595.8 1229.9 1330.3 1339.3 1358.3 1376.7 775.5 799.5 842.5 865.8 888.4 4591.2 4698.0 4734.9 5000.5 5190.0 4815.6 5318.2 5406.7 5700.7 5710.3 4423.7 4702.2 5130.8 5144.4 5192.0 2882.2 2973.9 3006.2 3029.6 3033.0 5145.8 5261.7 5895.6 5981.9 6005.7 4864.6 5112.6 5838.5 5870.4 5927.8 4878.6 5552.8 5574.0 5661.8 5992.1 4874.7 5453.8 5481.2 5704.8 5761.3 5614.2 5632.5 5641.5 5650.0 5664.5 4774.4 5521.4 5937.6 5949.9 5985.6 3379.6 3394.9 3640.0 3882.1 3946.1 4651.4 5357.0 5644.2 5676.5 5682.7 4681.7 4829.7 5167.7 5580.0 5592.2 5130.4 5211.3 5253.6 5531.5 5566.7 4692.9 4921.0 5084.3 5341.7 5415.5 5024.0 5407.2 5421.3 5576.7 5587.2 4537.4 5424.4 5445.0 5446.9 5463.6 22:07:25 INFO - PROCESS | 2403 | 22:07:25 INFO - PROCESS | 2403 | [#5] yui.html Cycles:180 Average:18312.13 Median:613.72 stddev:25780.69 (4200.7%) stddev-sans-first:25819.10 22:07:25 INFO - PROCESS | 2403 | Values: 701.8 759.9 771.2 821.0 837.1 14738.8 14921.4 15211.6 15225.9 15251.9 13874.8 13888.9 13922.7 13932.7 13951.7 715.7 749.3 874.8 900.1 911.9 898.0 911.1 914.5 916.6 918.4 977.4 981.2 981.4 981.7 982.6 753.4 787.1 788.9 789.4 973.0 687.8 723.0 724.1 724.7 725.4 14346.3 14431.7 14444.8 14463.0 14464.5 13170.1 13346.7 13401.9 13656.7 13686.9 11727.5 12055.2 12190.3 12319.2 12625.1 4404.1 4481.8 4512.0 4554.6 4571.5 81853.2 82475.0 82619.0 84731.6 85708.0 10678.1 10685.2 10792.8 10836.8 10894.2 21109.1 21152.3 21163.4 21167.5 21193.9 7564.8 7681.7 7842.0 7883.7 7996.0 4411.0 4428.9 4467.2 4481.6 4487.4 3283.4 3406.4 3409.0 3409.3 3411.9 580.9 588.3 650.7 654.3 659.9 543.8 590.3 619.0 630.3 638.3 8228.5 8617.7 8645.0 8731.6 8959.5 516.7 608.5 619.6 625.8 649.5 3572.4 4142.4 4144.0 4149.8 4168.8 91690.0 92349.5 93710.6 94898.5 95637.0 80041.0 80121.0 81021.6 81254.0 82448.6 52492.7 68153.9 69900.7 70235.3 73213.5 8068.6 8071.4 8080.3 8181.9 8229.8 47031.8 47186.1 47272.3 48203.0 48214.8 62326.7 65137.7 65179.0 65754.0 66262.0 10067.7 10568.7 10733.6 10987.3 11037.7 10270.9 11685.2 11980.5 12266.3 12318.4 9740.6 10052.6 10138.1 10155.9 11240.0 10621.5 10908.8 11006.3 11113.7 11254.3 10075.8 10997.2 11240.4 11270.5 11331.8 8699.2 9076.3 9132.9 9306.2 9518.6 9133.4 9154.8 9235.4 9349.9 9429.4 22:07:25 INFO - PROCESS | 2403 | -------- Summary: end -------- 22:07:25 INFO - PROCESS | 2403 | 22:07:26 INFO - TEST-INFO | 2403: exit 0 22:07:26 INFO - TEST-OK | dromaeo_css | took 1115463ms 22:07:26 INFO - TEST-START | kraken 22:07:26 INFO - operating with platform_type : mac_ 22:07:26 INFO - Initialising browser for kraken test... 22:07:26 INFO - TEST-INFO | started process 2406 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpVNqKLr/profile http://localhost:54437/getInfo.html) 22:07:28 INFO - PROCESS | 2406 | __metrics Screen width/height:1600/1200 22:07:28 INFO - PROCESS | 2406 | colorDepth:24 22:07:28 INFO - PROCESS | 2406 | Browser inner width/height: 1024/689 22:07:28 INFO - PROCESS | 2406 | __metrics 22:07:28 INFO - PROCESS | 2406 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:07:28 ERROR - PROCESS | 2406 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:07:29 INFO - TEST-INFO | 2406: exit 0 22:07:29 INFO - Browser initialized. 22:07:29 INFO - Running cycle 1/1 for kraken test... 22:07:29 INFO - TEST-INFO | started process 2407 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpVNqKLr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:41 INFO - PROCESS | 2407 | RSS: Main: 241725440 22:07:41 INFO - PROCESS | 2407 | 22:07:44 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54437/tests/kraken/driver.html?testName=audio-beat-detection) 22:07:45 INFO - PROCESS | 2407 | RSS: Main: 292323328 22:07:45 INFO - PROCESS | 2407 | 22:07:47 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54437/tests/kraken/driver.html?testName=audio-dft) 22:07:48 INFO - PROCESS | 2407 | RSS: Main: 301580288 22:07:48 INFO - PROCESS | 2407 | 22:07:52 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54437/tests/kraken/driver.html?testName=audio-fft) 22:07:52 INFO - PROCESS | 2407 | RSS: Main: 386105344 22:07:52 INFO - PROCESS | 2407 | 22:07:54 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54437/tests/kraken/driver.html?testName=audio-oscillator) 22:07:55 INFO - PROCESS | 2407 | RSS: Main: 287297536 22:07:55 INFO - PROCESS | 2407 | 22:07:57 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54437/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:07:57 INFO - PROCESS | 2407 | RSS: Main: 290795520 22:07:57 INFO - PROCESS | 2407 | 22:08:00 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54437/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:01 INFO - PROCESS | 2407 | RSS: Main: 329617408 22:08:01 INFO - PROCESS | 2407 | 22:08:04 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54437/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:04 INFO - PROCESS | 2407 | RSS: Main: 331771904 22:08:04 INFO - PROCESS | 2407 | 22:08:07 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54437/tests/kraken/driver.html?testName=json-parse-financial) 22:08:08 INFO - PROCESS | 2407 | RSS: Main: 330899456 22:08:08 INFO - PROCESS | 2407 | 22:08:09 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54437/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:08:10 INFO - PROCESS | 2407 | RSS: Main: 311640064 22:08:10 INFO - PROCESS | 2407 | 22:08:12 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:08:13 INFO - PROCESS | 2407 | RSS: Main: 318099456 22:08:13 INFO - PROCESS | 2407 | 22:08:15 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:08:16 INFO - PROCESS | 2407 | RSS: Main: 322969600 22:08:16 INFO - PROCESS | 2407 | 22:08:18 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:08:19 INFO - PROCESS | 2407 | RSS: Main: 324657152 22:08:19 INFO - PROCESS | 2407 | 22:08:22 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:08:22 INFO - PROCESS | 2407 | RSS: Main: 324968448 22:08:22 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:54437/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54437/tests/kraken/driver.html?testName=ai-astar) 22:08:24 INFO - PROCESS | 2407 | RSS: Main: 368623616 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | __start_tp_report 22:08:24 INFO - PROCESS | 2407 | _x_x_mozilla_page_load 22:08:24 INFO - PROCESS | 2407 | _x_x_mozilla_page_load_details 22:08:24 INFO - PROCESS | 2407 | |i|pagename|runs| 22:08:24 INFO - PROCESS | 2407 | |0;ai-astar;112;104;111;112;111;111;111;111;112;111 22:08:24 INFO - PROCESS | 2407 | |1;audio-beat-detection;155;123;119;170;142;113;133;117;151;116 22:08:24 INFO - PROCESS | 2407 | |2;audio-dft;182;182;183;182;185;183;184;185;180;183 22:08:24 INFO - PROCESS | 2407 | |3;audio-fft;81;73;56;105;55;56;60;60;92;54 22:08:24 INFO - PROCESS | 2407 | |4;audio-oscillator;88;88;91;74;71;68;94;70;68;99 22:08:24 INFO - PROCESS | 2407 | |5;imaging-gaussian-blur;74;113;134;129;87;85;88;85;88;84 22:08:24 INFO - PROCESS | 2407 | |6;imaging-darkroom;92;93;93;94;93;93;94;94;94;92 22:08:24 INFO - PROCESS | 2407 | |7;imaging-desaturate;84;84;87;86;84;87;84;86;101;130 22:08:24 INFO - PROCESS | 2407 | |8;json-parse-financial;57;56;57;60;53;55;57;57;56;55 22:08:24 INFO - PROCESS | 2407 | |9;json-stringify-tinderbox;65;65;81;75;72;71;72;72;69;70 22:08:24 INFO - PROCESS | 2407 | |10;stanford-crypto-aes;87;87;88;86;84;85;83;115;140;111 22:08:24 INFO - PROCESS | 2407 | |11;stanford-crypto-ccm;139;138;138;138;138;137;136;145;169;138 22:08:24 INFO - PROCESS | 2407 | |12;stanford-crypto-pbkdf2;166;164;165;163;164;163;163;162;162;162 22:08:24 INFO - PROCESS | 2407 | |13;stanford-crypto-sha256-iterative;67;66;66;65;64;65;64;65;66;66 22:08:24 INFO - PROCESS | 2407 | __end_tp_report 22:08:24 INFO - PROCESS | 2407 | __start_cc_report 22:08:24 INFO - PROCESS | 2407 | _x_x_mozilla_cycle_collect,1925 22:08:24 INFO - PROCESS | 2407 | __end_cc_report 22:08:24 INFO - PROCESS | 2407 | __startTimestamp1464671304882__endTimestamp 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | ------- Summary: start ------- 22:08:24 INFO - PROCESS | 2407 | Number of tests: 14 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.37 (2.1%) stddev-sans-first:2.46 22:08:24 INFO - PROCESS | 2407 | Values: 112.0 104.0 111.0 112.0 111.0 111.0 111.0 111.0 112.0 111.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:137.50 stddev:19.69 (14.3%) stddev-sans-first:19.35 22:08:24 INFO - PROCESS | 2407 | Values: 155.0 123.0 119.0 170.0 142.0 113.0 133.0 117.0 151.0 116.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.52 (0.8%) stddev-sans-first:1.58 22:08:24 INFO - PROCESS | 2407 | Values: 182.0 182.0 183.0 182.0 185.0 183.0 184.0 185.0 180.0 183.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#3] audio-fft Cycles:10 Average:69.20 Median:60.00 stddev:17.97 (29.9%) stddev-sans-first:18.54 22:08:24 INFO - PROCESS | 2407 | Values: 81.0 73.0 56.0 105.0 55.0 56.0 60.0 60.0 92.0 54.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:88.00 stddev:12.01 (13.7%) stddev-sans-first:12.48 22:08:24 INFO - PROCESS | 2407 | Values: 88.0 88.0 91.0 74.0 71.0 68.0 94.0 70.0 68.0 99.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:85.00 stddev:20.81 (24.5%) stddev-sans-first:20.38 22:08:24 INFO - PROCESS | 2407 | Values: 74.0 113.0 134.0 129.0 87.0 85.0 88.0 85.0 88.0 84.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.50 stddev:0.79 (0.8%) stddev-sans-first:0.71 22:08:24 INFO - PROCESS | 2407 | Values: 92.0 93.0 93.0 94.0 93.0 93.0 94.0 94.0 94.0 92.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:85.00 stddev:14.52 (17.1%) stddev-sans-first:15.16 22:08:24 INFO - PROCESS | 2407 | Values: 84.0 84.0 87.0 86.0 84.0 87.0 84.0 86.0 101.0 130.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.83 (3.2%) stddev-sans-first:1.92 22:08:24 INFO - PROCESS | 2407 | Values: 57.0 56.0 57.0 60.0 53.0 55.0 57.0 57.0 56.0 55.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:72.00 stddev:4.66 (6.5%) stddev-sans-first:4.37 22:08:24 INFO - PROCESS | 2407 | Values: 65.0 65.0 81.0 75.0 72.0 71.0 72.0 72.0 69.0 70.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#10] stanford-crypto-aes Cycles:10 Average:96.60 Median:85.50 stddev:19.09 (22.3%) stddev-sans-first:19.92 22:08:24 INFO - PROCESS | 2407 | Values: 87.0 87.0 88.0 86.0 84.0 85.0 83.0 115.0 140.0 111.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:138.00 stddev:9.92 (7.2%) stddev-sans-first:10.48 22:08:24 INFO - PROCESS | 2407 | Values: 139.0 138.0 138.0 138.0 138.0 137.0 136.0 145.0 169.0 138.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:163.50 stddev:1.35 (0.8%) stddev-sans-first:1.05 22:08:24 INFO - PROCESS | 2407 | Values: 166.0 164.0 165.0 163.0 164.0 163.0 163.0 162.0 162.0 162.0 22:08:24 INFO - PROCESS | 2407 | 22:08:24 INFO - PROCESS | 2407 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:0.83 22:08:24 INFO - PROCESS | 2407 | Values: 67.0 66.0 66.0 65.0 64.0 65.0 64.0 65.0 66.0 66.0 22:08:24 INFO - PROCESS | 2407 | -------- Summary: end -------- 22:08:24 INFO - PROCESS | 2407 | 22:08:25 INFO - TEST-INFO | 2407: exit 0 22:08:25 INFO - TEST-OK | kraken | took 59325ms 22:08:25 INFO - SUITE-END | took 1174s 22:08:26 INFO - Completed test suite (00:19:35) 22:08:26 INFO - javascript benchmark 22:08:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.932021359573, 3408.778429402087, 3424.6404127566607, 3458.8097654628987, 3472.0972547137753, 5560.555155587544, 5621.0, 5636.0, 5720.941625799225, 5770.596058275923, 5621.943780562193, 5682.318121825387, 5722.885542289152, 5757.42425757424, 5786.508146807516, 5060.645754797166, 5073.644844860862, 5144.228365745131, 5144.485551444863, 5242.056429842627, 5087.694738315695, 5232.659877107995, 5260.0, 5266.7366631668265, 5276.577873770108, 4496.0, 4983.953369792348, 5068.113080210948, 5114.28400023997, 5126.794928202868, 5081.034603425337, 5081.5426611605135, 5111.284420181178, 5136.871578210537, 5173.870653233662, 5057.418396884332, 5104.387473503166, 5132.358455193101, 5165.9225111623355, 5191.480851914816, 5495.065838807413, 5621.915671264935, 5702.686352250625, 5719.0, 5729.799457018984, 6099.115628233925, 6145.279321789906, 6145.446909778141, 6188.009918413078, 6206.937930620681, 5480.150576660624, 6229.0, 6293.559450838443, 6301.212348456443, 6306.936930630727, 5614.989301925692, 5659.830205093854, 5791.276090488646, 5803.5357171426185, 5817.0692689169955, 6197.4732147767945, 6275.152854364694, 6287.088372186007, 6294.244690637152, 6312.558120931582, 6099.4815440688035, 6105.700080128636, 6122.908156377658, 6127.662978536223, 6140.17107690465, 6061.818145455643, 6105.664188469676, 6228.532860035515, 6252.718627661766, 6267.561270711007, 5754.856128596735, 6046.395360463918, 6100.694965251809, 6102.72537735803, 6117.113018612276, 6085.421884920926, 6159.0, 6178.567500274938, 6181.320054793969, 6188.2883468400805, 4841.176999910023, 4846.975765121222, 4856.8300109495995, 4859.562639362474, 4874.147024270738, 4400.339949007611, 4423.20382331177, 4462.6876118672035, 4468.017036252084, 4475.26158183896, 5643.238162848045, 5648.6893220872425, 5649.0396632572565, 5664.0, 5678.801241956594, 6139.124180917401, 6203.441690247809, 6210.627362358273, 6218.720157592919, 6238.532110091761, 4738.47876733559, 5594.496495315441, 5682.488576028178, 5682.857928551819, 5706.600537962303, 5369.624126311121, 5382.91925621116, 5387.515123638891, 5395.622306438511, 5438.673679579238, 6244.625322480666, 6253.749850005949, 6259.749610015549, 6286.968565157145, 6327.905081423874, 6303.0, 6307.400796924285, 6314.810555683337, 6324.557280990287, 6325.905111423241, 6211.13044173807, 6307.842303942438, 6330.89209388346, 6346.238451385863, 6356.841078973062, 6066.181065556093, 6073.605215660968, 6129.02260737745, 6212.0, 6215.9378406217165, 5185.948140518546, 6245.406686364788, 6267.6866156692895, 6283.02613094971, 6285.874282514233, 5859.2382990211, 5947.434993675594, 6066.575339726177, 6079.088136779342, 6100.633961962297, 5977.7310021050025, 6066.636001839904, 6077.544184186116, 6097.359777223328, 6130.448259656652, 5319.760610772447, 5848.736806843617, 5870.735816888165, 5907.113932910098, 5946.881062378641, 5791.449812267828, 5976.103584462366, 5989.131575921379, 6063.587664112524, 6064.432105700125, 5948.791792287249, 5952.464278214981, 5952.523798096228, 5961.926853166298, 5964.373740757158], "value": 5648.67997978039, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.2503107977891, 517.0976645753159, 526.9367675878896, 543.6656456279389, 560.8569814697252, 22835.200767973125, 23040.308790736282, 25522.106726264585, 26477.735222647767, 26506.734932650666, 43402.56597434032, 44022.11955760883, 44179.77910110453, 46648.30027549581, 46851.82870428241, 9447.858282125773, 9634.566444510014, 9653.613855445808, 9670.419774813534, 9696.17582505488, 7579.81050473737, 8551.572421378938, 9585.137337639611, 9740.220782337432, 9765.925748167696, 8887.422317549372, 8954.2836573074, 8968.327375446836, 11437.828432573519, 11713.53145874164, 11497.252678575866, 11599.710007249801, 11606.129540284512, 11610.767784644344, 11644.825327620134, 11501.539938402453, 11626.186166968315, 11628.88371116295, 11639.941800290944, 11647.0, 31450.842745786125, 32013.6798632013, 32113.0, 33493.83253083719, 39404.423823047044, 3188.756385009848, 3533.3639944809925, 3979.1643754811525, 4184.4351012613215, 4218.535961044283, 31521.21196970094, 31779.205519861956, 31889.202769930704, 31942.722291108326, 32255.677443225737, 3196.280836811722, 3331.716804071657, 3358.429067058606, 3364.0076177527444, 3403.931921361559, 351802.0, 352896.0, 353264.0, 353806.0, 354310.99999999744, 57110.714446427504, 58160.70919645375, 58172.70913645405, 58238.0, 58329.83340333169, 60827.69586152129, 61044.694776526725, 61114.777704446555, 61275.69362153161, 61283.69358153181, 16342.0, 16603.41888033913, 16608.335666573203, 16612.750808737877, 16621.916890415472, 12980.610581682564, 13048.608541743763, 13050.15174013686, 13057.28184949837, 13107.475700971854, 13487.325633718276, 14308.427662893577, 15477.148756818258, 15482.071075735266, 15487.845121548866, 6053.27360716708, 6205.379462053758, 6954.965225173842, 7103.89344159838, 7200.279972002862, 907.3875134284332, 959.4962644611495, 1035.5547114740734, 1092.4865313302735, 1116.2688439072258, 26745.46509069808, 28925.119867208985, 29518.55722164169, 29987.250318741768, 30030.099097027123, 985.2167526816198, 1127.9266847654876, 1166.1312322319925, 1199.5141967503175, 1205.813098969661, 15214.847851521343, 15216.619584510476, 15450.918435709393, 15732.134732589817, 15862.920685396499, 667834.6608266927, 672804.0, 673924.0, 675673.0, 678393.0, 43156.56843431525, 50879.74560127176, 57805.71097144572, 59002.70498647479, 59018.70490647605, 1234.370471059766, 1267.4866678995022, 1448.6161167290677, 1472.6244807574215, 1480.2450750117303, 30017.187694607048, 30222.697773021988, 30512.237194070327, 30746.692533074383, 30929.69070309358, 50205.74897125491, 50491.747541262055, 50575.49424505708, 50582.49417505925, 50658.98682026412, 831697.0, 850019.0, 850372.7481362553, 851083.0, 856569.0, 39600.603993960845, 40828.59171408248, 41591.58408415877, 41918.0, 42820.78589607032, 47305.54738777962, 47464.28803568018, 48080.27879581879, 48158.759206203744, 48921.510784891696, 39445.60554394419, 39907.0, 40291.395629065, 40788.0, 40999.1800164001, 38392.6160738389, 38668.0, 38754.0311492215, 39157.60842391539, 39528.0, 38494.037649059, 38642.0, 38888.0, 38943.026424339616, 39136.2172756549, 33428.0, 34767.65232347745, 34876.9536913893, 35057.47413788744, 35066.64933350736, 20359.69460458124, 22662.093516259163, 24956.750432495443, 25073.99704011892, 25300.0], "value": 20135.218577982432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.31492082187543, 380.969522438205, 381.81291167328004, 384.85183204466273, 408.979551022449, 14897.925510372434, 15975.600609984727, 19051.333203337887, 21615.135394584217, 21644.783552164474, 20491.0, 20652.69020964683, 21076.578468430624, 21140.682889756627, 21264.936753162317, 5099.6430249882615, 5471.206675032118, 5472.616916815825, 5520.365158006821, 5537.947789919902, 3425.5033020212177, 4141.979290103561, 4154.584541545852, 4158.604932531404, 4952.801887924478, 4286.0, 6191.504679625642, 6192.659403732793, 6214.098955651428, 6228.626282423046, 4675.298705194214, 6060.151578778997, 6092.7258273377365, 6123.265208175003, 6202.069689546559, 5652.943470565283, 5900.114982752579, 5901.055831067051, 5911.201987731644, 5922.911156332659, 13375.598732037957, 13737.0, 20585.8970705147, 26314.6052809208, 26838.5974210387, 2183.748868880067, 2226.9665955010687, 2307.896144673477, 2781.8469984150865, 3061.755059595227, 9374.156325930699, 9973.65092221776, 10212.591496340137, 10293.330933489375, 10342.276040677156, 1780.6705759434417, 1830.8993005384698, 1843.3916807453643, 1875.812418758127, 1935.1001784170428, 103604.96395036028, 127235.0, 132901.99999999904, 133443.998340024, 134710.0, 11489.54041838317, 11747.588834390926, 11750.58872939443, 11754.943714634257, 11955.462004209832, 13014.15407998477, 13739.0, 13741.312934353242, 14981.925090374478, 15014.399424022917, 7417.592032438268, 7913.287804097544, 8030.5181689098845, 8202.138775428613, 8561.700340488052, 8474.703385381483, 8528.360372972053, 8620.95689521561, 8638.870416943751, 8657.307415406753, 4463.66522510813, 4552.385427967249, 4562.58936695699, 4567.1779079765965, 4596.88507787308, 1579.5103517909486, 1778.5642517583276, 1823.215345066229, 1830.5240637434363, 1890.9054547272856, 881.6605606841413, 913.5477938420524, 932.3287233192091, 972.9270304727174, 996.9750756231007, 19857.801421985885, 20543.79456205449, 20566.07452664634, 20676.483087922923, 20836.853973031324, 936.3866667332908, 941.7363138321281, 950.1068995144542, 983.8327484327682, 994.8358520844124, 10388.792224155475, 10647.84028239577, 11129.942655447881, 11169.888301117047, 11175.608853690244, 587147.0564709626, 592630.0, 595847.0207648934, 605237.0, 606012.0, 122863.3856830728, 123220.38389807993, 124167.0, 125819.0, 126293.0, 971.8493633486821, 983.0759086458849, 1001.2940876681922, 1004.2819384140229, 1005.4017859373661, 17652.558686032437, 18289.359872404402, 18370.448886533424, 18730.157142928874, 18827.623447531245, 52140.478595213564, 52819.0, 53275.733621331645, 53810.0, 54249.91500169896, 77796.0, 78948.60525697334, 79014.20985790298, 79314.20685793068, 80449.79325310243, 8080.434369594307, 8156.306713929145, 8205.384596155433, 8215.0, 8391.412601117863, 12299.446524906221, 12599.370031498573, 12679.175853569488, 12816.487340506275, 12888.742225155633, 10920.672379828618, 10942.23404361687, 11065.391403472886, 11156.553737850396, 11178.608748694081, 9577.952110239405, 10093.0, 10455.268131231045, 11002.889971100187, 11046.0, 10878.347299162075, 11130.888691112985, 11185.706930079152, 11322.490487928228, 11398.08815294791, 7986.84026319482, 8145.144759800136, 8234.013007383068, 8378.413511054168, 8474.830503389774, 10354.275200735876, 11256.099512039182, 11442.37066961325, 11464.025557827345, 11498.367589782642], "value": 9397.22848206291, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.6533366658336, 1535.8924875258733, 1579.060459026879, 1793.4529968359652, 1941.310834653697, 7661.387089032878, 7820.217978202177, 8515.53164575948, 8771.649134034647, 8780.517071561062, 8522.744317670464, 8760.780980475476, 8764.0, 8767.254783343424, 8785.868211976825, 2346.2843832631042, 2369.8815059247054, 2375.4655202579456, 2376.738558758535, 2377.9286621401384, 2343.3087239264373, 2366.514864452783, 2394.7006624171977, 2394.8084153267696, 2395.8682272475007, 2342.8594284342907, 2365.5860224460735, 2370.905163793455, 2383.332666853284, 2386.5107652931106, 2326.2904814031713, 2357.528494301138, 2361.622140457527, 2364.385259832456, 2374.750651181618, 2247.4156719252946, 2331.8367714260007, 2337.146941366406, 2338.1465764995824, 2344.6717459555675, 7867.645955931939, 8142.7557173284895, 8803.427777194525, 8882.289416846637, 8884.511351875644, 5143.897122057575, 5324.387695415038, 5330.9733451332495, 5390.1914712793005, 5390.649607775521, 3255.983720081408, 3264.7061764441078, 3550.751447398683, 3576.481410195533, 3628.2380700053022, 1071.8660167479065, 1072.3351522056298, 1076.3272954403499, 1086.190787863047, 1091.1489038549944, 11639.418029098511, 11757.764844703059, 11766.176367654267, 11788.233764805345, 11830.645080647595, 4614.307853821886, 4722.834700785456, 4861.173600487926, 4863.0, 4866.975665121722, 10224.335418197794, 10242.436665983443, 10255.28213025096, 10262.332948358333, 10270.897291026995, 5294.867628309246, 5382.703951282717, 5388.0, 5390.784368625289, 5393.164059570773, 4407.845725399596, 4780.545848144421, 4902.975485122552, 4909.4845041270155, 4926.359573255454, 3795.772253664789, 3829.923401531954, 3888.941665875014, 3918.5689574146822, 3937.114149316381, 1070.641335152717, 1180.9704757381135, 1249.28790589365, 1251.355551890777, 1255.6546949588935, 758.4425447296233, 883.8188171424842, 940.6924375118591, 955.9330846840654, 962.0860182826343, 6117.571769976148, 6160.322364539897, 6179.721912513947, 6249.843753906098, 6316.494680425555, 925.6297481007516, 925.8333499969935, 971.7473456901257, 1000.8148492528906, 1015.2537884654773, 4050.7772072536295, 4094.488188976378, 4123.60825721556, 4160.45914031174, 4166.395872598457, 10418.479076046167, 10729.678109656723, 10812.459377031117, 10813.513391897384, 10875.401852898009, 10356.585736570603, 10685.732856678489, 10775.0, 10782.67651970442, 10830.729231769268, 8069.273765361028, 8085.757427277191, 8183.263506284463, 8239.340852731768, 8242.392733417046, 5504.146857237135, 5878.588498805043, 5952.077427998667, 6065.332813390612, 6066.241719785027, 9446.433214007182, 9924.206063515046, 10200.540975655966, 10203.183745300506, 10219.23355748307, 10202.33684810485, 10254.487275636338, 10341.482925853526, 10453.268271221246, 10481.056704896597, 7427.740029098955, 7553.584552849646, 7573.848523029619, 7587.620618968919, 7594.31651151399, 8176.141505141874, 8207.179282071746, 8309.95845020771, 8310.21052999964, 8320.542370169456, 6550.6069635822005, 7250.202477727551, 7354.595497247703, 7483.738069167553, 7638.197989211053, 7221.0, 7282.854342913006, 7309.671064801991, 7443.069616297963, 7471.551706897603, 6985.860282794214, 7037.331453511908, 7067.469939754642, 7075.646217689198, 7092.787216383516, 4100.323446631272, 4878.682885612424, 5075.883305672746, 5112.642115051911, 5120.71836049006, 5075.746212689424, 5081.0854046272025, 5139.383274007143, 5160.432352441304, 5245.632805703717], "value": 4605.968779347982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.9021113243763, 308.6805156662854, 311.9516474946384, 316.7450202586918, 319.85926192475307, 4254.085371645096, 5308.681479111256, 5334.51989320961, 5340.279062326585, 5353.62524623276, 4645.790939407726, 5227.686338819664, 5244.606654500909, 5270.942853145176, 5296.9735151324285, 3613.7351926825663, 3962.32640451124, 3980.9402858956996, 3992.361222204448, 4005.319095753729, 3658.762180458275, 4000.319785743559, 4024.4969378827645, 4045.7167998240134, 4062.7608579383277, 3964.623360780721, 4013.2575473537345, 4021.3163762160357, 4047.3119569673227, 4056.9797151014204, 3553.591336996227, 3730.664240218366, 3831.3103641344546, 4033.7176397652174, 4055.026793569522, 3192.776505644606, 3489.4068008438626, 3506.8246587670515, 3520.8239588020497, 3542.4509201073874, 4211.599898009715, 4658.091672123937, 5060.822871199527, 5066.113430149709, 5100.38795344561, 3924.8029351047917, 4424.455937835757, 4535.9546404536195, 4578.175928332898, 4617.492075871652, 4720.952790472085, 5437.102878025121, 5517.531009864167, 5544.782087165167, 5552.583556233259, 3310.8344582771006, 3385.5937287525653, 3854.5374555053295, 3904.0, 3904.39481880309, 4643.6285097192485, 5110.923336149961, 5707.603975634533, 5779.3642699303045, 5913.615614985012, 3366.8316584170693, 3964.960350396517, 3968.8015599219925, 3973.7417067890497, 3978.4827972363987, 4006.198760247904, 4025.77858217795, 4028.2749105160765, 4052.49343832021, 4056.79716014204, 3905.7851818150275, 4192.958070419318, 4375.47494300686, 4426.424564806554, 4620.0, 5090.720010399464, 5138.4604616514725, 5219.765110570033, 5537.141743029837, 5595.804146854841, 1229.883161099694, 1330.2750001249342, 1339.2500199888093, 1358.280111540885, 1376.7315373502272, 775.5424299663226, 799.4923223752843, 842.4692443760514, 865.8311629232365, 888.3870129610548, 4591.150637132141, 4698.0, 4734.934639706039, 5000.474855169208, 5189.974050129726, 4815.6147508199965, 5318.202269659505, 5406.729663516809, 5700.657960522382, 5710.314762228559, 4423.699676168374, 4702.247640377524, 5130.7947682093045, 5144.382674079135, 5191.974040129775, 2882.1641723899834, 2973.895913643012, 3006.1733023418733, 3029.606381064691, 3032.9848350758107, 5145.76844042019, 5261.657992230493, 5895.55783316253, 5981.906332783491, 6005.669688167105, 4864.610831133501, 5112.590992720573, 5838.4745372915795, 5870.354261031283, 5927.792527261525, 4878.56092951636, 5552.805651802249, 5574.0, 5661.830145095653, 5992.093834490269, 4874.707517548958, 5453.7818487260065, 5481.232627432083, 5704.828855134353, 5761.279840019997, 5614.185943038155, 5632.493075623213, 5641.489537155926, 5649.971750141223, 5664.518515926193, 4774.40319960005, 5521.365043020104, 5937.554683398676, 5949.91075133882, 5985.6408615483215, 3379.5606571145595, 3394.9151271218416, 3639.9626106559504, 3882.1071153634257, 3946.145311703495, 4651.441826980784, 5357.0, 5644.153376993319, 5676.545876329967, 5682.698507104629, 4681.742504162304, 4829.73436460998, 5167.6641018333685, 5580.0, 5592.182540674378, 5130.358705161855, 5211.342215470062, 5253.579713622978, 5531.502164805187, 5566.721663916707, 4692.92960605598, 4920.991196804718, 5084.2627818966785, 5341.679499229903, 5415.458454154553, 5024.0, 5407.242985982044, 5421.2681288025615, 5576.665400076008, 5587.217789509553, 4537.387452693844, 5424.376196737426, 5445.047116754631, 5446.930166536849, 5463.617546771688], "value": 4017.0604418219227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.8491024429746, 759.8594260061889, 771.2441807029115, 821.0024819843885, 837.1461109668137, 14738.77891831622, 14921.403143874246, 15211.619709507268, 15225.923870380633, 15251.92374038131, 13874.791878121836, 13888.86111138891, 13922.651933701662, 13932.651683707913, 13951.720965580682, 715.7459102018761, 749.2806905370824, 874.7506960516254, 900.0639335091513, 911.8905731312219, 897.9820403591924, 911.0843602179833, 914.4604683236889, 916.5554705967601, 918.4030380252856, 977.3891317926297, 981.1709105805583, 981.4062492192228, 981.7202097402242, 982.6216906491017, 753.4198667026386, 787.1420152034253, 788.86589279822, 789.3685051958411, 972.9805403891918, 687.8383579858729, 723.0022568855, 724.0551080839502, 724.7354715528846, 725.3942957630385, 14346.282685865664, 14431.711365772626, 14444.783328250085, 14463.0, 14464.49374271906, 13170.078094533386, 13346.666333341647, 13401.932990335083, 13656.726865462635, 13686.931565342109, 11727.53089876404, 12055.21641093326, 12190.268583884907, 12319.199252048587, 12625.116241863074, 4404.11917616478, 4481.753503557303, 4511.977440112812, 4554.612857907082, 4571.519990401027, 81853.18146818515, 82475.0, 82619.0, 84731.57634211851, 85708.0, 10678.145748340114, 10685.167919700054, 10792.78414431707, 10836.837447438294, 10894.237403381687, 21109.050092745863, 21152.259670911444, 21163.365099047052, 21167.470813229484, 21193.894030530057, 7564.773056808304, 7681.6543255552515, 7841.960790196013, 7883.6846526138315, 7995.960020199862, 4411.0, 4428.933565996513, 4467.24056910326, 4481.619062379734, 4487.394201782718, 3283.4089863824265, 3406.386850366958, 3408.9659103409144, 3409.284050349405, 3411.8976430707116, 580.9078931608633, 588.2852334413708, 650.6876699184418, 654.2835595023441, 659.8746238214724, 543.8341305901738, 590.3358721438382, 618.9597676151036, 630.3224034163201, 638.2755572425331, 8228.465149765247, 8617.655293788304, 8644.956775216084, 8731.563421828883, 8959.462432254086, 516.6848222584221, 608.4797498139116, 619.615838180331, 625.7809766581751, 649.4998850884792, 3572.4462708280257, 4142.440770496005, 4144.023771300943, 4149.813258403379, 4168.8332466700995, 91690.0, 92349.53825230831, 93710.57884231537, 94898.52550737202, 95637.0, 80041.0, 80121.0, 81021.59489202517, 81254.0, 82448.58775706083, 52492.73753631131, 68153.93307301184, 69900.65049674718, 70235.2976470239, 73213.53572928619, 8068.573928781159, 8071.43499955021, 8080.313173380328, 8181.877271840808, 8229.83540329202, 47031.76484117557, 47186.056278874916, 47272.2909156356, 48203.0, 48214.794630133125, 62326.68836655788, 65137.674311628136, 65179.02231466627, 65754.0, 66262.0, 10067.69796906094, 10568.735781605521, 10733.570657173626, 10987.340759554452, 11037.668869933916, 10270.948645256725, 11685.240459370114, 11980.46087926028, 12266.282720419327, 12318.445669945053, 9740.610375584898, 10052.597896084075, 10138.138258248131, 10155.898441015495, 11240.0, 10621.468926553487, 10908.78182436331, 11006.337612915131, 11113.699791308223, 11254.268472549258, 10075.84886226722, 10997.175211858983, 11240.381779002233, 11270.458234671978, 11331.830022549504, 8699.173578510032, 9076.27389808827, 9132.908670913206, 9306.208972237435, 9518.61925522999, 9133.360664753403, 9154.771130721785, 9235.445873247358, 9349.85693063869, 9429.387089839138], "value": 6970.18434795522, "unit": "score"}], "name": "dromaeo_css", "value": 7187.379732447556, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 111.0, 112.0, 111.0, 111.0, 111.0, 111.0, 112.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 119.0, 170.0, 142.0, 113.0, 133.0, 117.0, 151.0, 116.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 183.0, 182.0, 185.0, 183.0, 184.0, 185.0, 180.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 56.0, 105.0, 55.0, 56.0, 60.0, 60.0, 92.0, 54.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 91.0, 74.0, 71.0, 68.0, 94.0, 70.0, 68.0, 99.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 113.0, 134.0, 129.0, 87.0, 85.0, 88.0, 85.0, 88.0, 84.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 86.0, 84.0, 87.0, 84.0, 86.0, 101.0, 130.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 60.0, 53.0, 55.0, 57.0, 57.0, 56.0, 55.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 81.0, 75.0, 72.0, 71.0, 72.0, 72.0, 69.0, 70.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 86.0, 84.0, 85.0, 83.0, 115.0, 140.0, 111.0], "value": 96.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 138.0, 138.0, 138.0, 137.0, 136.0, 145.0, 169.0, 138.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 165.0, 163.0, 164.0, 163.0, 163.0, 162.0, 162.0, 162.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 64.0, 65.0, 64.0, 65.0, 66.0, 66.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1453.4, "alertThreshold": 2.0}]} 22:08:26 INFO - Return code: 0 22:08:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:08:26 ERROR - # TBPL SUCCESS # 22:08:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:08:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:08:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:08:26 INFO - 2016-05-30 22:08:26.207 screenresolution[2411:38645] starting screenresolution argv=screenresolution get 22:08:26 INFO - 2016-05-30 22:08:26.210 screenresolution[2411:38645] Display 0: 1600x1200x32@60 22:08:26 INFO - 2016-05-30 22:08:26.216 screenresolution[2412:38649] starting screenresolution argv=screenresolution list 22:08:26 INFO - Available Modes on Display 0 22:08:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:08:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:08:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:08:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:08:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:08:26 INFO - Intel Iris: 22:08:26 INFO - Chipset Model: Intel Iris 22:08:26 INFO - Type: GPU 22:08:26 INFO - Bus: Built-In 22:08:26 INFO - VRAM (Dynamic, Max): 1536 MB 22:08:26 INFO - Vendor: Intel (0x8086) 22:08:26 INFO - Device ID: 0x0a2e 22:08:26 INFO - Revision ID: 0x0009 22:08:26 INFO - Displays: 22:08:26 INFO - Display: 22:08:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:08:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:08:26 INFO - Display Serial Number: 1600x1200 60 22:08:26 INFO - Main Display: Yes 22:08:26 INFO - Mirror: Off 22:08:26 INFO - Online: Yes 22:08:26 INFO - Rotation: Supported 22:08:26 INFO - Return code: 0 22:08:26 INFO - Running post-action listener: _resource_record_post_action 22:08:26 INFO - ##### 22:08:26 INFO - ##### Finished run-tests step (success) 22:08:26 INFO - ##### 22:08:26 INFO - Running post-run listener: _resource_record_post_run 22:08:26 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 827392; Write bytes: 696358400; Read time: 6; Write time: 1947 22:08:26 INFO - TinderboxPrint: CPU usage
53.7% 22:08:26 INFO - TinderboxPrint: I/O read bytes / time
827,392 / 6 22:08:26 INFO - TinderboxPrint: I/O write bytes / time
696,358,400 / 1,947 22:08:26 INFO - TinderboxPrint: CPU idle
2,240.8 (46.3%) 22:08:26 INFO - TinderboxPrint: CPU system
967.2 (20.0%) 22:08:26 INFO - TinderboxPrint: CPU user
1,633.7 (33.7%) 22:08:26 INFO - TinderboxPrint: Swap in / out
399,335,424 / 0 22:08:26 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378069504; Write bytes: 373293568; Read time: 28152; Write time: 799 22:08:26 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 827392; Write bytes: 293766144; Read time: 6; Write time: 1078 22:08:26 INFO - Running post-run listener: _upload_blobber_files 22:08:26 INFO - Blob upload gear active. 22:08:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:08:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:08:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:08:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:08:27 INFO - (blobuploader) - INFO - Open directory for files ... 22:08:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:08:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:08:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:28 INFO - (blobuploader) - INFO - Done attempting. 22:08:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:08:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:08:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:29 INFO - (blobuploader) - INFO - Done attempting. 22:08:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:08:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:08:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:08:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:08:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:08:31 INFO - (blobuploader) - INFO - Done attempting. 22:08:31 INFO - (blobuploader) - INFO - Iteration through files over. 22:08:31 INFO - Return code: 0 22:08:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:08:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:08:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecfba2b1480495637e365f495d5d8abb358cdda59b9a289bca6eed8a56eeda7a65258fa637885fa32f3ce36a76a8334f99cd7f67188ac3dd63a503bc75610904", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47fe131321f522af79745e3930100cd076e397828e2524c02345aa0e8c128b6252414e34c1ee90965839a29e614dd33aa67620c0120b5cf3b5443418d0510cdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/718fc2cb20b900586a7379adb472d8655985b2af52716237df8d97955e80d603b1550b7a2b9d5452d94ac369bf25468ffb784107c7ff7035ddf10b481b7dd243"} 22:08:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:08:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:08:31 INFO - Contents: 22:08:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecfba2b1480495637e365f495d5d8abb358cdda59b9a289bca6eed8a56eeda7a65258fa637885fa32f3ce36a76a8334f99cd7f67188ac3dd63a503bc75610904", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47fe131321f522af79745e3930100cd076e397828e2524c02345aa0e8c128b6252414e34c1ee90965839a29e614dd33aa67620c0120b5cf3b5443418d0510cdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/718fc2cb20b900586a7379adb472d8655985b2af52716237df8d97955e80d603b1550b7a2b9d5452d94ac369bf25468ffb784107c7ff7035ddf10b481b7dd243"} 22:08:31 INFO - Running post-run listener: copy_logs_to_upload_dir 22:08:31 INFO - Copying logs to upload dir... 22:08:31 INFO - mkdir: /builds/slave/test/build/upload/logs 22:08:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.043514 ========= master_lag: 1076.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 40 mins, 12 secs) (at 2016-05-30 22:26:28.139100) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-30 22:26:28.144645) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bdPhAZHaXz/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecfba2b1480495637e365f495d5d8abb358cdda59b9a289bca6eed8a56eeda7a65258fa637885fa32f3ce36a76a8334f99cd7f67188ac3dd63a503bc75610904", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47fe131321f522af79745e3930100cd076e397828e2524c02345aa0e8c128b6252414e34c1ee90965839a29e614dd33aa67620c0120b5cf3b5443418d0510cdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/718fc2cb20b900586a7379adb472d8655985b2af52716237df8d97955e80d603b1550b7a2b9d5452d94ac369bf25468ffb784107c7ff7035ddf10b481b7dd243"} build_url:https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ecfba2b1480495637e365f495d5d8abb358cdda59b9a289bca6eed8a56eeda7a65258fa637885fa32f3ce36a76a8334f99cd7f67188ac3dd63a503bc75610904", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47fe131321f522af79745e3930100cd076e397828e2524c02345aa0e8c128b6252414e34c1ee90965839a29e614dd33aa67620c0120b5cf3b5443418d0510cdf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/718fc2cb20b900586a7379adb472d8655985b2af52716237df8d97955e80d603b1550b7a2b9d5452d94ac369bf25468ffb784107c7ff7035ddf10b481b7dd243"}' ========= master_lag: 1.77 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-30 22:26:29.923702) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-30 22:26:29.924050) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bdPhAZHaXz/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.5VLL4Sv5LL/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005328 ========= master_lag: 5.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-30 22:26:35.097881) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-05-30 22:26:35.101252) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-05-30 22:26:38.323865) ========= ========= Total master_lag: 1083.30 =========