builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0214 starttime: 1464865777.61 results: success (0) buildid: 20160602025253 builduid: d274913f96674d629e3095fed6247a94 revision: 8d11755302e81158ba58f2a4f72ff6669399d11b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.605425) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.605861) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.606155) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.629406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.629780) ========= 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.2UYx0xx4dD/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.wd3MkppBmf/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-06-02 04:09:37-- 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% 156M=0s 2016-06-02 04:09:37 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095712 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.745736) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.746061) ========= 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.2UYx0xx4dD/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.wd3MkppBmf/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.026357 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.794479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:37.794788) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8d11755302e81158ba58f2a4f72ff6669399d11b --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 8d11755302e81158ba58f2a4f72ff6669399d11b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2UYx0xx4dD/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.wd3MkppBmf/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-06-02 04:09:37,880 truncating revision to first 12 chars 2016-06-02 04:09:37,880 Setting DEBUG logging. 2016-06-02 04:09:37,880 attempt 1/10 2016-06-02 04:09:37,880 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8d11755302e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 04:09:38,573 unpacking tar archive at: fx-team-8d11755302e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.948817 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:38.764119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:38.764545) ========= script_repo_revision: 8d11755302e81158ba58f2a4f72ff6669399d11b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:38.764975) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:38.765325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 04:09:38.782014) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-06-02 04:09:38.782565) ========= /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.2UYx0xx4dD/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.wd3MkppBmf/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:09:38 INFO - MultiFileLogger online at 20160602 04:09:38 in /builds/slave/test 04:09:38 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 04:09:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:09:38 INFO - {'append_to_log': False, 04:09:38 INFO - 'base_work_dir': '/builds/slave/test', 04:09:38 INFO - 'blob_upload_branch': 'Fx-Team', 04:09:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:09:38 INFO - 'branch': 'Fx-Team', 04:09:38 INFO - 'buildbot_json_path': 'buildprops.json', 04:09:38 INFO - 'config_files': ('talos/mac_config.py',), 04:09:38 INFO - 'default_actions': ('clobber', 04:09:38 INFO - 'read-buildbot-config', 04:09:38 INFO - 'download-and-extract', 04:09:38 INFO - 'populate-webroot', 04:09:38 INFO - 'create-virtualenv', 04:09:38 INFO - 'install', 04:09:38 INFO - 'run-tests'), 04:09:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:09:38 INFO - 'download_minidump_stackwalk': True, 04:09:38 INFO - 'download_symbols': 'ondemand', 04:09:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:09:38 INFO - 'tooltool.py': '/tools/tooltool.py', 04:09:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:09:38 INFO - '/tools/misc-python/virtualenv.py')}, 04:09:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:09:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:09:38 INFO - 'installer_path': 'installer.exe', 04:09:38 INFO - 'log_level': 'info', 04:09:38 INFO - 'log_name': 'talos', 04:09:38 INFO - 'log_to_console': True, 04:09:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:09:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:09:38 INFO - 'opt_config_files': (), 04:09:38 INFO - 'pip_index': False, 04:09:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:09:38 INFO - 'cmd': ('bash', 04:09:38 INFO - '-c', 04:09:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:09:38 INFO - 'enabled': True, 04:09:38 INFO - 'halt_on_failure': False, 04:09:38 INFO - 'name': 'check_screen_resolution'},), 04:09:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:09:38 INFO - 'cmd': ('bash', 04:09:38 INFO - '-c', 04:09:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:09:38 INFO - 'enabled': True, 04:09:38 INFO - 'halt_on_failure': False, 04:09:38 INFO - 'name': 'check_screen_resolution'},), 04:09:38 INFO - 'run_cmd_checks_enabled': True, 04:09:38 INFO - 'sps_profile': False, 04:09:38 INFO - 'sps_profile_interval': 0, 04:09:38 INFO - 'suite': 'dromaeojs', 04:09:38 INFO - 'system_bits': '32', 04:09:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:09:38 INFO - 'title': 't-yosemite-r7-0214', 04:09:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:09:38 INFO - 'use_talos_json': True, 04:09:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:09:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:09:38 INFO - 'work_dir': 'build'} 04:09:38 INFO - ##### 04:09:38 INFO - ##### Running clobber step. 04:09:38 INFO - ##### 04:09:38 INFO - Running pre-action listener: _resource_record_pre_action 04:09:38 INFO - Running main action method: clobber 04:09:38 INFO - rmtree: /builds/slave/test/build 04:09:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:09:40 INFO - Running post-action listener: _resource_record_post_action 04:09:40 INFO - ##### 04:09:40 INFO - ##### Finished clobber step (success) 04:09:40 INFO - ##### 04:09:40 INFO - ##### 04:09:40 INFO - ##### Running read-buildbot-config step. 04:09:40 INFO - ##### 04:09:40 INFO - Running pre-action listener: _resource_record_pre_action 04:09:40 INFO - Running main action method: read_buildbot_config 04:09:40 INFO - Using buildbot properties: 04:09:40 INFO - { 04:09:40 INFO - "project": "", 04:09:40 INFO - "product": "firefox", 04:09:40 INFO - "script_repo_revision": "production", 04:09:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:09:40 INFO - "repository": "", 04:09:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:09:40 INFO - "buildid": "20160602025253", 04:09:40 INFO - "pgo_build": "False", 04:09:40 INFO - "basedir": "/builds/slave/test", 04:09:40 INFO - "buildnumber": 48, 04:09:40 INFO - "slavename": "t-yosemite-r7-0214", 04:09:40 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:09:40 INFO - "platform": "yosemite_r7", 04:09:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:09:40 INFO - "branch": "fx-team", 04:09:40 INFO - "repo_path": "integration/fx-team", 04:09:40 INFO - "revision": "8d11755302e81158ba58f2a4f72ff6669399d11b", 04:09:40 INFO - "stage_platform": "macosx64", 04:09:40 INFO - "builduid": "d274913f96674d629e3095fed6247a94", 04:09:40 INFO - "slavebuilddir": "test" 04:09:40 INFO - } 04:09:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:09:40 INFO - Running post-action listener: _resource_record_post_action 04:09:40 INFO - ##### 04:09:40 INFO - ##### Finished read-buildbot-config step (success) 04:09:40 INFO - ##### 04:09:40 INFO - ##### 04:09:40 INFO - ##### Running download-and-extract step. 04:09:40 INFO - ##### 04:09:40 INFO - Running pre-action listener: _resource_record_pre_action 04:09:40 INFO - Running main action method: download_and_extract 04:09:40 INFO - mkdir: /builds/slave/test/build/tests 04:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:40 INFO - https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:09:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:09:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:09:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:42 INFO - Downloaded 1199 bytes. 04:09:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:09:42 INFO - Using the following test package requirements: 04:09:42 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:09:42 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:09:42 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:09:42 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'jsshell-mac.zip'], 04:09:42 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:09:42 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:09:42 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:09:42 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:09:42 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:09:42 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:09:42 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:09:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:42 INFO - https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:09:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:09:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:09:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:44 INFO - Downloaded 18697444 bytes. 04:09:44 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'] 04:09:44 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 04:09:47 INFO - Return code: 0 04:09:47 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 04:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:47 INFO - https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:09:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:09:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:09:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:48 INFO - Downloaded 18697444 bytes. 04:09:48 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'] 04:09:48 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 04:09:52 INFO - Return code: 0 04:09: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')]} 04:09:52 INFO - https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:09:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:09:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:09:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:53 INFO - Downloaded 10834055 bytes. 04:09:53 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'] 04:09:53 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 04:09:54 INFO - Return code: 0 04:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:09:54 INFO - https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:09:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:09:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:09:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zh-mARNqTIW1perE1UJgZg/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 04:09:59 INFO - Downloaded 111046155 bytes. 04:09:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:09:59 INFO - mkdir: /builds/slave/test/properties 04:09:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:59 INFO - Contents: 04:09:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:10:01 INFO - Running post-action listener: _resource_record_post_action 04:10:01 INFO - Running post-action listener: set_extra_try_arguments 04:10:01 INFO - ##### 04:10:01 INFO - ##### Finished download-and-extract step (success) 04:10:01 INFO - ##### 04:10:01 INFO - ##### 04:10:01 INFO - ##### Running populate-webroot step. 04:10:01 INFO - ##### 04:10:01 INFO - Running pre-action listener: _resource_record_pre_action 04:10:01 INFO - Running main action method: populate_webroot 04:10:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:10:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:10:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:10:01 INFO - '--tppagecycles', 04:10:01 INFO - '7'], 04:10:01 INFO - 'tests': ['tsvgm']}}, 04:10:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:10:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:10:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:10:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:10:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['damp', 'tps']}, 04:10:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'tests': ['damp', 'tps']}, 04:10:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['dromaeo_dom']}, 04:10:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:10:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['a11yr', 04:10:01 INFO - 'ts_paint', 04:10:01 INFO - 'tpaint', 04:10:01 INFO - 'sessionrestore', 04:10:01 INFO - 'sessionrestore_no_auto_restore', 04:10:01 INFO - 'tabpaint']}, 04:10:01 INFO - 'other-e10s': {'tests': ['a11yr', 04:10:01 INFO - 'ts_paint', 04:10:01 INFO - 'tpaint', 04:10:01 INFO - 'sessionrestore', 04:10:01 INFO - 'sessionrestore_no_auto_restore', 04:10:01 INFO - 'tabpaint']}, 04:10:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:10:01 INFO - 'ts_paint', 04:10:01 INFO - 'tpaint', 04:10:01 INFO - 'sessionrestore', 04:10:01 INFO - 'sessionrestore_no_auto_restore', 04:10:01 INFO - 'tabpaint']}, 04:10:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:10:01 INFO - 'ts_paint', 04:10:01 INFO - 'tpaint', 04:10:01 INFO - 'sessionrestore', 04:10:01 INFO - 'sessionrestore_no_auto_restore', 04:10:01 INFO - 'tabpaint']}, 04:10:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['a11yr', 04:10:01 INFO - 'ts_paint', 04:10:01 INFO - 'tpaint', 04:10:01 INFO - 'sessionrestore', 04:10:01 INFO - 'sessionrestore_no_auto_restore', 04:10:01 INFO - 'tabpaint']}, 04:10:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['a11yr', 04:10:01 INFO - 'ts_paint', 04:10:01 INFO - 'tpaint', 04:10:01 INFO - 'sessionrestore', 04:10:01 INFO - 'sessionrestore_no_auto_restore', 04:10:01 INFO - 'tabpaint']}, 04:10:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['tsvgx', 04:10:01 INFO - 'tsvgr_opacity', 04:10:01 INFO - 'tart', 04:10:01 INFO - 'tscrollx', 04:10:01 INFO - 'cart']}, 04:10:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:10:01 INFO - 'tsvgr_opacity', 04:10:01 INFO - 'tart', 04:10:01 INFO - 'tscrollx', 04:10:01 INFO - 'cart']}, 04:10:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'talos_options': ['--disable-e10s'], 04:10:01 INFO - 'tests': ['tp5o']}, 04:10:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'tests': ['tp5o']}, 04:10:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'talos_options': ['--disable-e10s', 04:10:01 INFO - '--xperf_path', 04:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:01 INFO - 'tests': ['tp5n']}, 04:10:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:01 INFO - 'talos_options': ['--xperf_path', 04:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:01 INFO - 'tests': ['tp5n']}}, 04:10:01 INFO - 'talos.zip': {'path': '', 04:10:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:10:01 INFO - Running post-action listener: _resource_record_post_action 04:10:01 INFO - ##### 04:10:01 INFO - ##### Finished populate-webroot step (success) 04:10:01 INFO - ##### 04:10:01 INFO - ##### 04:10:01 INFO - ##### Running create-virtualenv step. 04:10:01 INFO - ##### 04:10:01 INFO - Running pre-action listener: _resource_record_pre_action 04:10:01 INFO - Running main action method: create_virtualenv 04:10:01 INFO - Creating virtualenv /builds/slave/test/build/venv 04:10:01 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 04:10:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:10:01 INFO - Using real prefix '/tools/python27' 04:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:10:02 INFO - Installing distribute.............................................................................................................................................................................................done. 04:10:04 INFO - Installing pip.................done. 04:10:04 INFO - Return code: 0 04:10:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:04 INFO - 'HOME': '/Users/cltbld', 04:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:04 INFO - 'LOGNAME': 'cltbld', 04:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:04 INFO - 'MOZ_NO_REMOTE': '1', 04:10:04 INFO - 'NO_EM_RESTART': '1', 04:10:04 INFO - 'PAGER': '/bin/cat', 04:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:04 INFO - 'PWD': '/builds/slave/test', 04:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:04 INFO - 'SHELL': '/bin/bash', 04:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:04 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:04 INFO - 'USER': 'cltbld', 04:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:04 INFO - 'XPC_FLAGS': '0x0', 04:10:04 INFO - 'XPC_SERVICE_NAME': '0', 04:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:05 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 04:10:05 INFO - Cleaning up... 04:10:05 INFO - Return code: 0 04:10:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:05 INFO - 'HOME': '/Users/cltbld', 04:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:05 INFO - 'LOGNAME': 'cltbld', 04:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:05 INFO - 'MOZ_NO_REMOTE': '1', 04:10:05 INFO - 'NO_EM_RESTART': '1', 04:10:05 INFO - 'PAGER': '/bin/cat', 04:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:05 INFO - 'PWD': '/builds/slave/test', 04:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:05 INFO - 'SHELL': '/bin/bash', 04:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:05 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:05 INFO - 'USER': 'cltbld', 04:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:05 INFO - 'XPC_FLAGS': '0x0', 04:10:05 INFO - 'XPC_SERVICE_NAME': '0', 04:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:05 INFO - Downloading/unpacking psutil>=3.1.1 04:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:08 INFO - Installing collected packages: psutil 04:10:08 INFO - Running setup.py install for psutil 04:10:08 INFO - building 'psutil._psutil_osx' extension 04:10:08 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 04:10:08 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 04:10:08 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 04:10:09 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 04:10:09 INFO - building 'psutil._psutil_posix' extension 04:10:09 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 04:10:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:10:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:10:09 INFO - ^ 04:10:09 INFO - 1 warning generated. 04:10:09 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 04:10:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:09 INFO - Successfully installed psutil 04:10:09 INFO - Cleaning up... 04:10:09 INFO - Return code: 0 04:10:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:10:09 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')]} 04:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:09 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')]} 04:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:09 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:09 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 04:10:09 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 04:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:09 INFO - 'HOME': '/Users/cltbld', 04:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:09 INFO - 'LOGNAME': 'cltbld', 04:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:09 INFO - 'MOZ_NO_REMOTE': '1', 04:10:09 INFO - 'NO_EM_RESTART': '1', 04:10:09 INFO - 'PAGER': '/bin/cat', 04:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:09 INFO - 'PWD': '/builds/slave/test', 04:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:09 INFO - 'SHELL': '/bin/bash', 04:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:09 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:09 INFO - 'USER': 'cltbld', 04:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:09 INFO - 'XPC_FLAGS': '0x0', 04:10:09 INFO - 'XPC_SERVICE_NAME': '0', 04:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10: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 04:10:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:10:12 INFO - Installing collected packages: mozsystemmonitor 04:10:12 INFO - Running setup.py install for mozsystemmonitor 04:10:12 INFO - Successfully installed mozsystemmonitor 04:10:12 INFO - Cleaning up... 04:10:12 INFO - Return code: 0 04:10:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:10:12 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')]} 04:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:12 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')]} 04:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:12 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:12 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 04:10:12 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 04:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:12 INFO - 'HOME': '/Users/cltbld', 04:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:12 INFO - 'LOGNAME': 'cltbld', 04:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:12 INFO - 'MOZ_NO_REMOTE': '1', 04:10:12 INFO - 'NO_EM_RESTART': '1', 04:10:12 INFO - 'PAGER': '/bin/cat', 04:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:12 INFO - 'PWD': '/builds/slave/test', 04:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:12 INFO - 'SHELL': '/bin/bash', 04:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:12 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:12 INFO - 'USER': 'cltbld', 04:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:12 INFO - 'XPC_FLAGS': '0x0', 04:10:12 INFO - 'XPC_SERVICE_NAME': '0', 04:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:12 INFO - Downloading/unpacking blobuploader==1.2.4 04:10:12 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 04:10:12 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 04:10:12 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 04:10:12 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 04:10:12 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 04:10:12 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 04:10:15 INFO - Downloading blobuploader-1.2.4.tar.gz 04:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:10:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:10:15 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 04:10:15 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 04:10:15 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 04:10:15 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 04:10:15 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 04:10:15 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 04:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:10:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:16 INFO - Downloading docopt-0.6.1.tar.gz 04:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:10:16 INFO - Installing collected packages: blobuploader, requests, docopt 04:10:16 INFO - Running setup.py install for blobuploader 04:10:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:10:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:10:16 INFO - Running setup.py install for requests 04:10:17 INFO - Running setup.py install for docopt 04:10:17 INFO - Successfully installed blobuploader requests docopt 04:10:17 INFO - Cleaning up... 04:10:17 INFO - Return code: 0 04:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10: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')]} 04:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10: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')]} 04:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:17 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:17 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 04:10:17 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 04:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:17 INFO - 'HOME': '/Users/cltbld', 04:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:17 INFO - 'LOGNAME': 'cltbld', 04:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:17 INFO - 'MOZ_NO_REMOTE': '1', 04:10:17 INFO - 'NO_EM_RESTART': '1', 04:10:17 INFO - 'PAGER': '/bin/cat', 04:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:17 INFO - 'PWD': '/builds/slave/test', 04:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:17 INFO - 'SHELL': '/bin/bash', 04:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:17 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:17 INFO - 'USER': 'cltbld', 04:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:17 INFO - 'XPC_FLAGS': '0x0', 04:10:17 INFO - 'XPC_SERVICE_NAME': '0', 04:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:17 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-TGyOmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:17 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-VL2k7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-560Nka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-pX8ceU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-c2tKn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-0un0lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-56is8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-ck1utf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-XBVzN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-fUx1MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-38bJA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-3uacs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-_g2mad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-bvwfbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-kL4Tny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-n0m9jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-7hYV2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:10:19 INFO - Running setup.py install for manifestparser 04:10:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:10:19 INFO - Running setup.py install for mozcrash 04:10:19 INFO - Running setup.py install for mozdebug 04:10:20 INFO - Running setup.py install for mozdevice 04:10:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Running setup.py install for mozfile 04:10:20 INFO - Running setup.py install for mozhttpd 04:10:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Running setup.py install for mozinfo 04:10:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Running setup.py install for mozInstall 04:10:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:10:20 INFO - Running setup.py install for mozleak 04:10:20 INFO - Running setup.py install for mozlog 04:10:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:10:21 INFO - Running setup.py install for moznetwork 04:10:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:10:21 INFO - Running setup.py install for mozprocess 04:10:21 INFO - Running setup.py install for mozprofile 04:10:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:10:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:10:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:10:21 INFO - Running setup.py install for mozrunner 04:10:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:10:21 INFO - Running setup.py install for mozscreenshot 04:10:21 INFO - Running setup.py install for moztest 04:10:22 INFO - Running setup.py install for mozversion 04:10:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:10:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:10:22 INFO - Cleaning up... 04:10:22 INFO - Return code: 0 04:10:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:22 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')]} 04:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:22 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')]} 04:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:22 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:22 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 04:10:22 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 04:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:22 INFO - 'HOME': '/Users/cltbld', 04:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:22 INFO - 'LOGNAME': 'cltbld', 04:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:22 INFO - 'MOZ_NO_REMOTE': '1', 04:10:22 INFO - 'NO_EM_RESTART': '1', 04:10:22 INFO - 'PAGER': '/bin/cat', 04:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:22 INFO - 'PWD': '/builds/slave/test', 04:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:22 INFO - 'SHELL': '/bin/bash', 04:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:22 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:22 INFO - 'USER': 'cltbld', 04:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:22 INFO - 'XPC_FLAGS': '0x0', 04:10:22 INFO - 'XPC_SERVICE_NAME': '0', 04:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-8kTcsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:22 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)) 04:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-09AvdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:22 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)) 04:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-vAw_1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:22 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)) 04:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-I2SvK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:22 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)) 04:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-C5y1Pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oyKcao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-_UdQt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-rsw6zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-kKZ_W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-BfvRrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Oq4qYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-hHWGzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-DNcPxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-BUJ1qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:23 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)) 04:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-WI21yC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:24 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)) 04:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:24 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-L1H6f7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:24 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)) 04:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:24 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-RL2b_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:24 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)) 04:10:24 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)) 04:10:24 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)) 04:10:24 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)) 04:10:24 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)) 04:10:24 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)) 04:10:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:26 INFO - Downloading blessings-1.6.tar.gz 04:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:10:26 INFO - Installing collected packages: blessings 04:10:26 INFO - Running setup.py install for blessings 04:10:27 INFO - Successfully installed blessings 04:10:27 INFO - Cleaning up... 04:10:27 INFO - Return code: 0 04:10:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:27 INFO - Reading from file tmpfile_stdout 04:10:27 INFO - Current package versions: 04:10:27 INFO - blessings == 1.6 04:10:27 INFO - blobuploader == 1.2.4 04:10:27 INFO - docopt == 0.6.1 04:10:27 INFO - manifestparser == 1.1 04:10:27 INFO - mozInstall == 1.12 04:10:27 INFO - mozcrash == 0.17 04:10:27 INFO - mozdebug == 0.1 04:10:27 INFO - mozdevice == 0.48 04:10:27 INFO - mozfile == 1.2 04:10:27 INFO - mozhttpd == 0.7 04:10:27 INFO - mozinfo == 0.9 04:10:27 INFO - mozleak == 0.1 04:10:27 INFO - mozlog == 3.2 04:10:27 INFO - moznetwork == 0.27 04:10:27 INFO - mozprocess == 0.22 04:10:27 INFO - mozprofile == 0.28 04:10:27 INFO - mozrunner == 6.11 04:10:27 INFO - mozscreenshot == 0.1 04:10:27 INFO - mozsystemmonitor == 0.3 04:10:27 INFO - moztest == 0.7 04:10:27 INFO - mozversion == 1.4 04:10:27 INFO - psutil == 3.1.1 04:10:27 INFO - requests == 1.2.3 04:10:27 INFO - wsgiref == 0.1.2 04:10:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:27 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 04:10:27 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 04:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:27 INFO - 'HOME': '/Users/cltbld', 04:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:27 INFO - 'LOGNAME': 'cltbld', 04:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:27 INFO - 'MOZ_NO_REMOTE': '1', 04:10:27 INFO - 'NO_EM_RESTART': '1', 04:10:27 INFO - 'PAGER': '/bin/cat', 04:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:27 INFO - 'PWD': '/builds/slave/test', 04:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:27 INFO - 'SHELL': '/bin/bash', 04:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:27 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:27 INFO - 'USER': 'cltbld', 04:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:27 INFO - 'XPC_FLAGS': '0x0', 04:10:27 INFO - 'XPC_SERVICE_NAME': '0', 04:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 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)) 04:10:27 INFO - Cleaning up... 04:10:27 INFO - Return code: 0 04:10:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:27 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:27 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 04:10:27 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 04:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:27 INFO - 'HOME': '/Users/cltbld', 04:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:27 INFO - 'LOGNAME': 'cltbld', 04:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:27 INFO - 'MOZ_NO_REMOTE': '1', 04:10:27 INFO - 'NO_EM_RESTART': '1', 04:10:27 INFO - 'PAGER': '/bin/cat', 04:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:27 INFO - 'PWD': '/builds/slave/test', 04:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:27 INFO - 'SHELL': '/bin/bash', 04:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:27 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:27 INFO - 'USER': 'cltbld', 04:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:27 INFO - 'XPC_FLAGS': '0x0', 04:10:27 INFO - 'XPC_SERVICE_NAME': '0', 04:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:28 INFO - Downloading/unpacking jsonschema 04:10:28 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 04:10:28 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 04:10:28 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 04:10:28 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 04:10:28 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 04:10:28 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 04:10:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:10:30 INFO - Downloading/unpacking functools32 (from jsonschema) 04:10:30 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 04:10:30 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 04:10:30 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 04:10:30 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 04:10:30 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 04:10:30 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 04:10:30 INFO - Downloading functools32-3.2.3-2.tar.gz 04:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:10:31 INFO - warning: no files found matching '*.txt' 04:10:31 INFO - no previously-included directories found matching 'build' 04:10:31 INFO - no previously-included directories found matching 'dist' 04:10:31 INFO - no previously-included directories found matching '.git*' 04:10:31 INFO - Installing collected packages: jsonschema, functools32 04:10:31 INFO - Running setup.py install for functools32 04:10:31 INFO - warning: no files found matching '*.txt' 04:10:31 INFO - no previously-included directories found matching 'build' 04:10:31 INFO - no previously-included directories found matching 'dist' 04:10:31 INFO - no previously-included directories found matching '.git*' 04:10:31 INFO - Successfully installed jsonschema functools32 04:10:31 INFO - Cleaning up... 04:10:31 INFO - Return code: 0 04:10:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:10:31 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')]} 04:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:31 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')]} 04:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:31 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 0x10345fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103465570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc012576230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, '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.2UYx0xx4dD/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.wd3MkppBmf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 04:10:31 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 04:10:31 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 04:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:31 INFO - 'HOME': '/Users/cltbld', 04:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:31 INFO - 'LOGNAME': 'cltbld', 04:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:31 INFO - 'MOZ_NO_REMOTE': '1', 04:10:31 INFO - 'NO_EM_RESTART': '1', 04:10:31 INFO - 'PAGER': '/bin/cat', 04:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:31 INFO - 'PWD': '/builds/slave/test', 04:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:31 INFO - 'SHELL': '/bin/bash', 04:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:10:31 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:31 INFO - 'USER': 'cltbld', 04:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:31 INFO - 'XPC_FLAGS': '0x0', 04:10:31 INFO - 'XPC_SERVICE_NAME': '0', 04:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:31 INFO - Downloading/unpacking flake8 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:31 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 04:10:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:10:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:33 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 04:10:34 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 04:10:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:10:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:34 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 04:10:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:10:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:35 INFO - no previously-included directories found matching 'docs/_build' 04:10:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:10:35 INFO - Running setup.py install for pep8 04:10:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:10:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:10:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:10:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:10:35 INFO - no previously-included directories found matching 'docs/_build' 04:10:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:10:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:10:35 INFO - Cleaning up... 04:10:35 INFO - Return code: 0 04:10:35 INFO - Running post-action listener: _resource_record_post_action 04:10:35 INFO - Running post-action listener: _start_resource_monitoring 04:10:35 INFO - Starting resource monitoring. 04:10:35 INFO - ##### 04:10:35 INFO - ##### Finished create-virtualenv step (success) 04:10:35 INFO - ##### 04:10:35 INFO - ##### 04:10:35 INFO - ##### Running install step. 04:10:35 INFO - ##### 04:10:35 INFO - Running pre-action listener: _resource_record_pre_action 04:10:35 INFO - Running main action method: install 04:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:10:35 INFO - Reading from file tmpfile_stdout 04:10:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:10:36 INFO - Reading from file tmpfile_stdout 04:10:36 INFO - Output received: 04:10:36 INFO - Usage: mozinstall [options] installer 04:10:36 INFO - Options: 04:10:36 INFO - -h, --help show this help message and exit 04:10:36 INFO - -d DEST, --destination=DEST 04:10:36 INFO - Directory to install application into. [default: 04:10:36 INFO - "/builds/slave/test"] 04:10:36 INFO - --app=APP Application being installed. [default: firefox] 04:10:36 INFO - mkdir: /builds/slave/test/build/application 04:10:36 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'] 04:10:36 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 04:11:11 INFO - Reading from file tmpfile_stdout 04:11:11 INFO - Output received: 04:11:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:11:11 INFO - Running post-action listener: _resource_record_post_action 04:11:11 INFO - ##### 04:11:11 INFO - ##### Finished install step (success) 04:11:11 INFO - ##### 04:11:11 INFO - ##### 04:11:11 INFO - ##### Running run-tests step. 04:11:11 INFO - ##### 04:11:11 INFO - Running pre-action listener: _resource_record_pre_action 04:11:11 INFO - Running main action method: run_tests 04:11:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:11:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:11:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:11:11 INFO - 2016-06-02 04:11:11.160 screenresolution[1648:7652] starting screenresolution argv=screenresolution get 04:11:11 INFO - 2016-06-02 04:11:11.162 screenresolution[1648:7652] Display 0: 1600x1200x32@60 04:11:11 INFO - 2016-06-02 04:11:11.169 screenresolution[1649:7654] starting screenresolution argv=screenresolution list 04:11:11 INFO - Available Modes on Display 0 04:11:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:11:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:11:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:11:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:11:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:11:11 INFO - Intel Iris: 04:11:11 INFO - Chipset Model: Intel Iris 04:11:11 INFO - Type: GPU 04:11:11 INFO - Bus: Built-In 04:11:11 INFO - VRAM (Dynamic, Max): 1536 MB 04:11:11 INFO - Vendor: Intel (0x8086) 04:11:11 INFO - Device ID: 0x0a2e 04:11:11 INFO - Revision ID: 0x0009 04:11:11 INFO - Displays: 04:11:11 INFO - Display: 04:11:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:11:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:11:11 INFO - Display Serial Number: 1600x1200 60 04:11:11 INFO - Main Display: Yes 04:11:11 INFO - Mirror: Off 04:11:11 INFO - Online: Yes 04:11:11 INFO - Rotation: Supported 04:11:11 INFO - Return code: 0 04:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:11:11 INFO - Python 2.7.3 04:11:11 INFO - Return code: 0 04:11:11 INFO - grabbing minidump binary from tooltool 04:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:11 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 0x103303d30>, '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 0x103127db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1032fab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:11:11 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 04:11:11 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 04:11:11 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 04:11:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:11:11 INFO - Return code: 0 04:11:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:11:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:11:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:11:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:11:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:11:12 INFO - Return code: 0 04:11:12 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/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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 04:11:12 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/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0214 --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 04:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2UYx0xx4dD/Render', 04:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:12 INFO - 'HOME': '/Users/cltbld', 04:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:12 INFO - 'LOGNAME': 'cltbld', 04:11:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:11:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:12 INFO - 'MOZ_NO_REMOTE': '1', 04:11:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:11:12 INFO - 'NO_EM_RESTART': '1', 04:11:12 INFO - 'PAGER': '/bin/cat', 04:11:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:12 INFO - 'PWD': '/builds/slave/test', 04:11:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:12 INFO - 'SHELL': '/bin/bash', 04:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wd3MkppBmf/Listeners', 04:11:12 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 04:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:12 INFO - 'USER': 'cltbld', 04:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:12 INFO - 'XPC_FLAGS': '0x0', 04:11:12 INFO - 'XPC_SERVICE_NAME': '0', 04:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:12 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/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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 04:11:12 INFO - mozversion application_buildid: 20160602025253 04:11:12 INFO - mozversion application_changeset: 8d11755302e81158ba58f2a4f72ff6669399d11b 04:11:12 INFO - mozversion application_display_name: Nightly 04:11:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:11:12 INFO - mozversion application_name: Firefox 04:11:12 INFO - mozversion application_remotingname: firefox 04:11:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:11:12 INFO - mozversion application_vendor: Mozilla 04:11:12 INFO - mozversion application_version: 49.0a1 04:11:12 INFO - mozversion platform_buildid: 20160602025253 04:11:12 INFO - mozversion platform_changeset: 8d11755302e81158ba58f2a4f72ff6669399d11b 04:11:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:11:12 INFO - mozversion platform_version: 49.0a1 04:11:12 INFO - using testdate: 1464865872 04:11:12 INFO - actual date: 1464865872 04:11:12 INFO - starting webserver on 'localhost:49275' 04:11:12 INFO - SUITE-START | Running 2 tests 04:11:12 INFO - TEST-START | dromaeo_css 04:11:12 INFO - operating with platform_type : mac_ 04:11:12 INFO - Initialising browser for dromaeo_css test... 04:11:12 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpCO9xbv/profile http://localhost:49275/getInfo.html) 04:11:15 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 04:11:15 INFO - PROCESS | 1667 | colorDepth:24 04:11:15 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 04:11:15 INFO - PROCESS | 1667 | __metrics 04:11:15 INFO - PROCESS | 1667 | 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" 04:11:15 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:11:15 INFO - TEST-INFO | 1667: exit 0 04:11:15 INFO - Browser initialized. 04:11:15 INFO - Running cycle 1/1 for dromaeo_css test... 04:11:15 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpCO9xbv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:11:27 INFO - PROCESS | 1669 | RSS: Main: 241799168 04:11:27 INFO - PROCESS | 1669 | 04:12:19 INFO - PROCESS | 1669 | 1464865939889 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] 04:14:29 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 04:14:31 INFO - PROCESS | 1669 | RSS: Main: 365842432 04:14:31 INFO - PROCESS | 1669 | 04:17:34 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 04:17:34 INFO - PROCESS | 1669 | RSS: Main: 378085376 04:17:34 INFO - PROCESS | 1669 | 04:20:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 04:20:37 INFO - PROCESS | 1669 | RSS: Main: 370884608 04:20:37 INFO - PROCESS | 1669 | 04:23:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 04:23:40 INFO - PROCESS | 1669 | RSS: Main: 372846592 04:23:40 INFO - PROCESS | 1669 | 04:26:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 04:26:44 INFO - PROCESS | 1669 | RSS: Main: 337510400 04:26:44 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 04:29:48 INFO - PROCESS | 1669 | RSS: Main: 379944960 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | __start_tp_report 04:29:48 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 04:29:48 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 04:29:48 INFO - PROCESS | 1669 | |i|pagename|runs| 04:29:48 INFO - PROCESS | 1669 | |0;dojo.html;3424.0754996151036;3443.845026973786;3444.380011597913;3472.9652703472957;3519.7712148710348;5564.972175139119;5643.435656434354;5660.801871934483;5713.885722285552;5716.6284191527575;5650.74571644276;5701.486866182053;5754.16564598134;5795.884082318351;5836.970815145918;4182.874513764592;5079.6698214615935;5141.717205553712;5142.202958541432;5227.764750586214;5238.214267859814;5251.868703282429;5262.8684282892855;5298.894022119555;5327.946720532784;5075.984803039407;5119.641625086246;5128.71792051437;5151.536361727425;5158.458361871987;5168.22476628505;5180.430152683201;5184.922226166611;5191.377034755854;5197.558207552363;5087.974560127176;5114.974425127888;5120.308758317617;5140.1518749406305;5160.92258616121;5551.444855514416;5555.2222688823595;5616.831495055155;5644.717764111778;5717;6171.87656246877;6269.090981807658;6276.246850377938;6281.466075383598;6296.118543403927;6241.28225254097;6249.0626406039;6323.968380158116;6344.270408902989;6348.555601107924;5628.493435590775;5681.318241810968;5719.742611582489;5788.392218816964;5801.50687191585;6211.937880621136;6245.219347581552;6264.21697287839;6265.248170219603;6266.968665156645;6142.078688196806;6160.599561028608;6162.876742465126;6171.537134714914;6180;6096.375503402309;6161.8459538511;6243.562950593415;6244.219472565929;6304.306526282197;6207.534434917309;6220.253569571681;6226.315105338409;6226.968865155736;6242.875142497124;5970.253718285215;6229.1590635264565;6248.625082495065;6250.15622890913;6250.406211409909;4858;4891.461939186686;4901.995091006335;4910.189818679878;4938.456769755324;4395.099004704028;4417.182821178092;4421.5578442156175;4458.175237580993;4483.125790470827;5624.803131890364;5634;5643.5767317451355;5646.491815736604;5689.971550142305;6092.817215483543;6159.260888693386;6185;6241.438270555581;6278.46633036197;5377.789997250666;5643.633163844337;5646.0683987141665;5648.15277708347;5676.716164191774;4739.454962679267;5302.363716353985;5347.197920311906;5387;5400.189971504227;6173.660448675366;6204.410580994799;6220.626762394271;6233.065040244;6267.905981410283;6089.756409743561;6277.623342599459;6285.120083188266;6290.654014029273;6310.936890631035;6321.5258855586935;6322.589031712924;6331.493480521639;6346.809595712136;6353.0152826312;4918.975405122951;6054.061620448838;6126.914660831389;6162.198914141133;6209.875802483834;6281.214848143982;6294.874102518015;6306.747730090745;6309.053641953744;6331.2085989251345;6075.1798507200965;6095.542834287535;6115.266168059683;6142.539309551712;6150.292716337678;5841.094630332305;5902.026165682614;5908.172855800105;5970.58205925581;5992.610480318765;5530;5972.193753843176;5993.340732519332;6017.217761690952;6051.183090282755;5953.880922381615;6124.859813084112;6128.816135515942;6129.820301844934;6130.3369889931655;5742.712864356849;5807.448292412214;5838.532917366685;5915;5947.167396564398 04:29:48 INFO - PROCESS | 1669 | |1;ext.html;489.98530044098646;497.0357506437511;500.68707058088694;518.0933366608435;540.8485624025274;31425.371492570135;41873.16253674928;43218.351724724074;43364;43382.349264761055;44371;44428;44436.77781611095;44452.99999999992;45334.77332613341;9461.668841590546;9660.613575456973;9692.612295508206;9692.806143877118;9699.951500242525;9524.333296669236;9670;9671.419714817099;9688.273379496532;9714.902850971506;9669.854952175723;9692.321537492344;9706.029397060272;9706.466144362093;9714.271429642806;9691.51542422876;9694.903050969471;9702.660406885725;9716.222702183808;9803.705888823311;8063.516189028677;9507.619695212183;9571.617135314578;9576.138147566682;9592.760180995461;40894.591054089375;41228.587714123074;41246.38130428046;41290.38064429036;41596.088273820846;3568.536090308269;3914.725969182158;3946.52641682997;4149.543550209474;4370.038591509863;31363;31482.842585787155;31530;31995.52006719901;32310;3223.4197844388;3258.657840926716;3286.027711343488;3300.7689461737687;3324.7672662913606;503294;504487;504759.952400475;505928.99999999633;506271;63597.04604430937;63920.680396597716;64352.67823660852;64442.677786610766;64470.35529644643;34195.829020855235;35234.64765352365;65158.67420662866;65479.345206547325;65611.67194163999;16140.435084771976;16325.428609998593;16329.101899395648;16353.83646163547;16398.180090995404;11929.403529823474;12038.81941770874;12096.697582560506;12097.153199275966;12102.515899364103;16680.666386672197;19773.406797796088;19845.908475033724;19876.801231987494;20103.597928041356;7615.961920190474;8171.591420429074;8198.590070496452;8261.339092872557;8346.457480263764;884.6505630276005;965.75856035991;971.5288085278635;1044.237706474278;1158.623447379605;29559.817607295896;30685.539716904263;31043.534346984812;31476.213094672818;31775.364492710018;971.5870754929127;1141.8401423800674;1155.3010428690689;1196.5094311332311;1200.1598880783486;15710.057396556243;16017.279222435018;16041.919790401207;16374.345026198818;16433.671326573403;692975;695161.5241923758;697855;698750;698857;61905.071423928675;62336;62429;62430.0635490468;62624.68687656624;1281.9935900320625;1286.7233544787916;1409.823772028517;1434.3545404567985;1456.2281990545027;26678;31979.68020319767;32135.67864321327;32275.35449291048;32736.83631581827;53986.73006634942;54077;55193.89612207653;55219;55291.44708552863;617565;633094;633332;635488.8225558843;637299;35879.46180807342;37289.440658389605;45349.31976020296;46147;47414.76292618515;49442.25836612382;50208;50562.747186263834;50742.492575073775;50899;34486.655133448345;38566;39936.4009539863;41387.75836724903;41396.793016034724;40564.39153412642;40673.32804018862;41067.383989239584;41392.79303603463;41864.372034420114;39494.01264968399;40192.39711404273;40239.3904243827;40291.798541007105;40635.390469143575;31293;32078.35843283168;35143.12834091002;36460;36538.26923461569;25456.109036184385;25611;26018.797836005237;26174.59346256946;26333.60499592546 04:29:48 INFO - PROCESS | 1669 | |2;jquery.html;322.82244765379045;374.18987891215505;375.8271195250184;389.71550767939385;403.63471058691874;19745.11146998385;19783.40649780507;20860.791392086074;21137.894310528427;21161.894190529063;9927.900720992788;12196.695082622917;15963;19416.737912035744;20988.950552472354;5164.819231326914;5165.8450246492475;5273.578113750891;5368.865778355534;5380.757865896025;3826.8469261229516;4146.129312844307;4396.20868243716;6651.800445986604;6691.895837186858;6619.139511863451;6638.634875081869;6645.700943457532;6655.101561289212;6659.300773418794;6293.402126797947;6480.31956644555;6506.3818937200895;6596.835079123036;6609.702563384635;3695.7597756146033;4881.536254055859;5413.296271484721;5658.8019419320535;5747.798827041033;13440.260785656785;13524;13549.661258468519;20158;25718.87140564285;2119.4595378178465;2342.5783358995373;2358.6344116661944;2410.8191885608476;2840.957385639217;9499.667511637059;9993.25050621199;10013.449260290681;10369.22230832683;10387.27289089764;1813.839142948514;1853.7868145163343;1894.232102875037;1912.9521761955923;1916.1377380178976;80479.5976020122;96981;109420.4528977358;114580.42709786481;120416;9438.90561094394;10011.749706257257;10126.848097278547;10224.284300098921;10386.844197337046;12326.75346493065;12418.441170147365;12472.937635311946;12481.812772808415;12490.937545312216;7564.130125035581;7887.250711182429;8081.191880811872;8098.554579498066;8171.387145964077;6839.726410943506;7209.423246140296;8235.094139644634;8276.793080173044;8330.666773329;3892.8442862285756;3917.5690674025836;3920.31394505966;3960.980195099006;3965.8810235692977;1714.4685147604282;1870.2310838142503;1918.2518817661123;1938.9709154362695;1989.671704168796;829.2246749289434;913.7030465098869;951.378406292214;997.436448406648;1029.2075102171436;17852.642947141245;20908.163673453197;20980.685289720666;21006.369808905452;21238.89380553087;910.3127139010005;910.899801021887;946.1768261612441;960.4525420510242;961.6201600367815;8862.815615484325;9419.858702119474;10265.435401052864;10717.14262858985;11322.92432218938;624943;630986;633432;634369;645229;106306.9369306297;137224;138896.30551847175;140418;147762.26118869553;887.4808237181321;969.8690676758688;991.0832479956011;991.1228562721899;991.1476130527741;18787.530311741768;18900.810991889906;18940.621187576446;18962.52593685169;19118.713219302;53448;56039;56460.71769641126;56700;57795.42204577901;79299;79531.60234198792;79979.80030299434;81275.78086328828;81324.59337703274;7943.602819859099;8008.679652813822;8093.797655058671;8171.632276547451;8353.415260931919;12020.218685785396;12496.250224986166;12759.553415630407;12764.361781911053;12819.679508012374;10605.666753314259;10951.069159121564;11043;11075.612353567587;11144.888551114385;9541.713748587554;10663.840042399526;10725.463726813472;10814.29707069038;11018.669439916815;11114.444277785917;11221.990020898158;11382.15431201065;11430.14273929442;11439.828402574134;4882.414110306786;7974.38536759163;8001;8208.425410221227;8240.711575094843;9049.276057915482;11715.707107322385;11722.648320550397;11804.70488237801;11841.171118021655 04:29:48 INFO - PROCESS | 1669 | |3;mootools.html;1397;1549.0628169957176;1567.5297410776768;1820.9544761380964;1978.3075923426802;7909.6836126554945;8371.204735550129;8378.958105209473;8385.413021088527;8393.70622028229;8584.699535516258;8910.866337004934;8911.643534258621;9014.233790127848;9016.233620142279;2024.2611446821877;2297.977400056971;2386.6658667786437;2387.3912152401113;2389.2951579284154;2146.5384942237415;2324.790768830805;2361.53949979754;2394.2577800881695;2414.927552173429;2368.5854975379325;2399.5800734871414;2401.2916189724074;2403.9759602404015;2406.4104294447798;2352.9529409411803;2356.057576969209;2390.8804559771943;2393.7845593896545;2412.6260429633435;2091.4248581640018;2402.9639555406684;2431.222008957134;2432.2581612608146;2435.2329016359968;8679.17547832955;8990.730278091667;8992.280617550581;9002.05478424769;9025.864612030824;4186.979065104655;5251.212318152269;5441.59188060893;5462.180672899057;5470.206820011076;3473.426884564044;3585.731070169748;3609.783412995229;3685.539307586552;3694.058015206132;1074.1943542343242;1096.1395304685784;1102.728379538154;1103.6799328194813;1115.5147510832733;9999.150072243796;12135.93932030343;12165.93917030418;12177.269363838199;12203.755924881452;4208.72643278192;4556.111558246176;4676.485586585472;4692.296155576691;4692.700576717452;9340.019297973751;9340.159385655188;9343.2525397968;9350.438973661601;9359.578818953163;5246.186841039645;5313;5315.973420132875;5316.946830531645;5319.414864364917;4029;4436.112777444524;4572.0627271409285;4896.975515122402;4958.206686930074;3801.5248093988253;3826.3686491728554;3851.768893866321;3876.7480113792512;3908.4528166056775;1142.4287856071965;1144.49070163778;1147.3345459633392;1159.4144956796763;1164.8078067118831;643.6974621927686;813.8494378539989;924.5562130177418;954.71358592422;984.3208186351395;5875.20684707559;5933.881322373529;6019.69901504923;6091;6191.597546159485;473.6707987948412;479.8172504775745;492.8151943021367;495.0149203085833;590.0352922970974;3899.3566061599518;3916.373380259202;4031.778252196157;4091.2226676931286;4113.136241389342;10484.685459436228;10704.411257380763;10891.292066015683;10901.83647245292;10902.672919812418;10345.586176553004;10757.731056723644;10770.976757208053;10860.565577376974;10961.83557246642;8149.225823546753;8296.668133274601;8372.204640559137;8390.958045209734;8391.958040209882;5982.7307771149535;6144.078388241621;6151.753929842936;6207.317195108444;6218.689065546795;9994.400335979864;10256.333338332985;10263.23025773055;10267.691969240936;10282.331648442827;9903.950480247553;10197.541110650187;10309.896901031194;10464.162866970777;10477.738056548647;7598.468107232441;7659.578723170056;7735.381169506539;7758.650860711395;7787.532748035136;8210.055843578066;8348.540830254151;8375.16248375182;8388.916110838814;8443.95778021106;7495;7592.772216833504;7609.961950190435;7654.823248352094;7686.769396918102;7447.590382529013;7457.328840404172;7514.887276690744;7550.697972081275;7620.618969051636;7023.648817558999;7165.605891676008;7170.175429825428;7171.1394632644415;7173.1392232932385;4993.251012348177;5046.4701206374275;5077.898442031064;5086.974565127299;5165.715885626177;5022.246663000433;5087.974560127176;5132.666376685504;5242.397124330599;5247.134222853339 04:29:48 INFO - PROCESS | 1669 | |4;prototype.html;295.4032853635655;304.2090564532216;311.56536631399194;313.0811069510984;317.08520917154016;4934.851954441364;5218.556422704065;5236.371635403752;5283.233931079991;5307.548858347045;4037.7173597848164;4329.783510824462;4911.557959783619;5030.522100400456;5181.844544663666;3670.6145854685274;3720.2373513429748;3996.2806694794926;4020.2763502569524;4042.43405923171;3431.811250381228;3479.234568394949;4022.3162062449455;4026.3089502409057;4081.9183616327655;3206.679332066798;3720.9069773255615;3870.4000879863524;3961.1681546875193;4015.317396042665;3466.94799578004;3934.783586902719;3965.405189221611;3979.721419500622;3999.0204848599838;2933.5159698649695;3021.2597913511113;3305.834708264601;3414.846331915045;3496.160921378876;4707.693999889996;4711.057788442326;4815.783289751969;4998.500449865026;5044.218146187347;4428.0922410906005;4456.866294011184;4476.61948734358;4483.39474170986;4504.662150338738;4285.507166675841;5034.974825125888;5035.622328325354;5112.386513618352;5509.5041446270025;3227.080282119585;3408.0968543336035;3676.283124790667;3708.6847617952235;3755.8873233802756;5379.435159308295;5456.345238571397;5718.285214348165;5736.447280959975;5807.793038645372;3752.8311225994894;3765.755225910307;3767.6420740029653;3800.0619683961377;3803.801802432225;3838.4302598583586;3888.766673999569;3892.980535097363;3895.824902704287;3905.5703872573995;4092.9590704093175;4155.439015732898;4211.347241177622;4471.28459446487;4532.70537415067;4792.496787837296;4880.072786170616;4934.4572097069295;5096.413912400121;5303.488505775836;1143.0969534067992;1312.3766211049865;1320.5312114199512;1335.3523541082568;1339.5110784563565;812.8740045292989;849.8512760266929;867.5468590111537;892.7321803459066;895.5611750242465;4029.911923780563;4290.635295999875;4984.028114517707;5035.571976381975;5056.064628043824;4655.95344046562;4723.527647235249;5127.564157046693;5281.894362112813;5631.408702086222;4550;4673.976630116896;4706.976465117653;4996.825111121093;5012.323336349619;2478.5662509060844;2548.8980440782534;2551.6937967444023;2682.906098286551;2922.327864591156;4876.756427111064;5066.569341605931;5074.188129899198;5797.304323481125;5801.448862358069;4890.388701412324;4897.9020419592125;4917.926231106536;5207.843764687065;5247.658902171423;4425.402570652921;5748.798792042342;5765.4811067004175;5772.740226689809;5830.62100963436;5325.707086110301;5551.333839939233;5556.749946252347;5568.415316391883;5592.008275572549;4561.475430325555;4712.986707857827;5010.7745151469;5336.345732822837;5506.504414602705;5277.313949186581;5760.539156867357;5771;5789.073748200268;5799.913001305068;3312.668733126668;3674.1812802662853;3725.7578257413184;3753.6434038766274;3778.9433158503193;4810.326554282473;5161.690298582097;5715.456826656862;5737.397573254915;5744.425557444222;4700.858974230779;5099.974500127476;5297;5362.490563396471;5513.972430137824;4580.541945805393;4642.628589712883;4882.316475693477;5523.364813046391;5565.16522521625;4789.760511974457;5088.338515992898;5117.411497677815;5239.24031015493;5444.646098003617;4673.976630116964;4855.72238738857;5283.841484755463;5452.727363631723;5474.8905021898545;5343.0649636314265;5357.785688572413;5369.704666243394;5375.962581141643;5385.219143224289 04:29:48 INFO - PROCESS | 1669 | |5;yui.html;702.1994925784605;733.4242619543659;748.685552068131;840.4453060979755;846.4751853850612;14649.121052736844;15233.542993710178;15251.847481525196;15258.923705381472;16116;15804.972676776002;16052.277647505862;16102.19489025547;16124.355025798954;16146.515604531884;627.6610630259654;732.0044739154772;907.4464576608264;909.9272058235325;920.3327587499076;883.2360008592553;913.9497327646977;915.7207051849183;916.4043371808311;919.586186216202;947.947862867542;957.3394357893067;958.3674774648733;959.2278216036088;959.6113574002541;747.8429529798749;771.9922800772033;772.3782355204058;951.1439704266147;952.4095061062117;569.9316082070137;570.848725087852;684.2712511175632;707.9823004424879;708.8050786033831;14871.925640371837;14924.62688432787;14949.775753363709;14955.850441495664;14957.252137393087;13060.738785224243;13173.407196676173;13265.86734132656;13316;13513.324333783272;12143.332116733576;12431.316277604728;12543;12600.873991260154;12878.678033049155;4426.490953540352;4499.208343114573;4574.199515084847;4634.189016922025;4639.39687840582;78878.81681774721;78938;79242.41515169664;79289.20710792876;79605.42762006662;10082.29423940317;10302.124319432782;10550.736231594117;10993.780124397626;11012.17408694351;19200.231990720495;20073.197072117244;20117.597648046958;20163.596728065357;20178.091985860672;7804.102528209215;7826.647800848976;7866.567338796421;7872;7875.054993400829;4264.104538047045;4405.84579539721;4420.093880754438;4426.764686941133;4512.571305725951;2958.689337619519;3368.831558422069;3475.6350583188905;3490.947635785465;3492.371373152857;572.9799457018985;656.7602824968948;661.2263651527628;663.2372771312932;673.7473447457204;567.2823877794593;599.02060131685;600.5495878091432;634.5336177909234;646.8221239159269;9783.853242201372;10243.846342304721;10256.07695307426;10392.324498907547;10412.739681508023;674.3829396102511;680.5406350713348;694.207520581469;720.1538192623647;725.1904615658966;4387.956120438818;4450.020661877097;4460.888477788081;4468.4637843458995;4611.5619016193405;94345.5282723582;96111;96362.51818741002;96692;96994;69198.65400673065;85287.57356213179;85528.71706924401;87018;87111.69332460019;74455.5108897819;74666;74940;75862.62068689622;76791.61604192056;8878.378513503993;8918.598663060047;8922.73231803047;8924.955375223082;9090.954545227232;43183.13633727245;44271.58062080916;44685;45361.54638453573;46370.768146159055;43572;48853;62654.37345626485;63153;63657.36342636514;12174.513019479122;12515.937420312841;12614.180078294881;12865.807012894627;13503.189808611514;14012.64968375758;14846.851531484546;14917.179555124572;14977.1013739176;15336.309866055832;12712.050453039616;13340.332983350987;13387.665308367368;13529.86470135286;14068.859311407165;11816.527338906692;13679.384427700976;13790;13975.30123493801;13990.860091398956;13229.47082116743;13293.26887021223;13317.733645327233;13446.193228405935;13463.192208467523;11222.158338124837;11253.549858005916;11404.132580749236;11404.201705880509;11655.30068195911;9640.163027772429;10996.505157268102;11140.610078647209;11164.553417863519;11283.717907052389 04:29:48 INFO - PROCESS | 1669 | __end_tp_report 04:29:48 INFO - PROCESS | 1669 | __start_cc_report 04:29:48 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1252 04:29:48 INFO - PROCESS | 1669 | __end_cc_report 04:29:48 INFO - PROCESS | 1669 | __startTimestamp1464866988468__endTimestamp 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | ------- Summary: start ------- 04:29:48 INFO - PROCESS | 1669 | Number of tests: 6 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5708.60 Median:5908.17 stddev:647.69 (11.0%) stddev-sans-first:624.39 04:29:48 INFO - PROCESS | 1669 | Values: 3424.1 3443.8 3444.4 3473.0 3519.8 5565.0 5643.4 5660.8 5713.9 5716.6 5650.7 5701.5 5754.2 5795.9 5837.0 4182.9 5079.7 5141.7 5142.2 5227.8 5238.2 5251.9 5262.9 5298.9 5327.9 5076.0 5119.6 5128.7 5151.5 5158.5 5168.2 5180.4 5184.9 5191.4 5197.6 5088.0 5115.0 5120.3 5140.2 5160.9 5551.4 5555.2 5616.8 5644.7 5717.0 6171.9 6269.1 6276.2 6281.5 6296.1 6241.3 6249.1 6324.0 6344.3 6348.6 5628.5 5681.3 5719.7 5788.4 5801.5 6211.9 6245.2 6264.2 6265.2 6267.0 6142.1 6160.6 6162.9 6171.5 6180.0 6096.4 6161.8 6243.6 6244.2 6304.3 6207.5 6220.3 6226.3 6227.0 6242.9 5970.3 6229.2 6248.6 6250.2 6250.4 4858.0 4891.5 4902.0 4910.2 4938.5 4395.1 4417.2 4421.6 4458.2 4483.1 5624.8 5634.0 5643.6 5646.5 5690.0 6092.8 6159.3 6185.0 6241.4 6278.5 5377.8 5643.6 5646.1 5648.2 5676.7 4739.5 5302.4 5347.2 5387.0 5400.2 6173.7 6204.4 6220.6 6233.1 6267.9 6089.8 6277.6 6285.1 6290.7 6310.9 6321.5 6322.6 6331.5 6346.8 6353.0 4919.0 6054.1 6126.9 6162.2 6209.9 6281.2 6294.9 6306.7 6309.1 6331.2 6075.2 6095.5 6115.3 6142.5 6150.3 5841.1 5902.0 5908.2 5970.6 5992.6 5530.0 5972.2 5993.3 6017.2 6051.2 5953.9 6124.9 6128.8 6129.8 6130.3 5742.7 5807.4 5838.5 5915.0 5947.2 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:74785.96 Median:42545.76 stddev:164836.70 (387.4%) stddev-sans-first:165204.72 04:29:48 INFO - PROCESS | 1669 | Values: 490.0 497.0 500.7 518.1 540.8 31425.4 41873.2 43218.4 43364.0 43382.3 44371.0 44428.0 44436.8 44453.0 45334.8 9461.7 9660.6 9692.6 9692.8 9700.0 9524.3 9670.0 9671.4 9688.3 9714.9 9669.9 9692.3 9706.0 9706.5 9714.3 9691.5 9694.9 9702.7 9716.2 9803.7 8063.5 9507.6 9571.6 9576.1 9592.8 40894.6 41228.6 41246.4 41290.4 41596.1 3568.5 3914.7 3946.5 4149.5 4370.0 31363.0 31482.8 31530.0 31995.5 32310.0 3223.4 3258.7 3286.0 3300.8 3324.8 503294.0 504487.0 504760.0 505929.0 506271.0 63597.0 63920.7 64352.7 64442.7 64470.4 34195.8 35234.6 65158.7 65479.3 65611.7 16140.4 16325.4 16329.1 16353.8 16398.2 11929.4 12038.8 12096.7 12097.2 12102.5 16680.7 19773.4 19845.9 19876.8 20103.6 7616.0 8171.6 8198.6 8261.3 8346.5 884.7 965.8 971.5 1044.2 1158.6 29559.8 30685.5 31043.5 31476.2 31775.4 971.6 1141.8 1155.3 1196.5 1200.2 15710.1 16017.3 16041.9 16374.3 16433.7 692975.0 695161.5 697855.0 698750.0 698857.0 61905.1 62336.0 62429.0 62430.1 62624.7 1282.0 1286.7 1409.8 1434.4 1456.2 26678.0 31979.7 32135.7 32275.4 32736.8 53986.7 54077.0 55193.9 55219.0 55291.4 617565.0 633094.0 633332.0 635488.8 637299.0 35879.5 37289.4 45349.3 46147.0 47414.8 49442.3 50208.0 50562.7 50742.5 50899.0 34486.7 38566.0 39936.4 41387.8 41396.8 40564.4 40673.3 41067.4 41392.8 41864.4 39494.0 40192.4 40239.4 40291.8 40635.4 31293.0 32078.4 35143.1 36460.0 36538.3 25456.1 25611.0 26018.8 26174.6 26333.6 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35390.92 Median:2108.28 stddev:105553.72 (5006.6%) stddev-sans-first:105816.98 04:29:48 INFO - PROCESS | 1669 | Values: 322.8 374.2 375.8 389.7 403.6 19745.1 19783.4 20860.8 21137.9 21161.9 9927.9 12196.7 15963.0 19416.7 20989.0 5164.8 5165.8 5273.6 5368.9 5380.8 3826.8 4146.1 4396.2 6651.8 6691.9 6619.1 6638.6 6645.7 6655.1 6659.3 6293.4 6480.3 6506.4 6596.8 6609.7 3695.8 4881.5 5413.3 5658.8 5747.8 13440.3 13524.0 13549.7 20158.0 25718.9 2119.5 2342.6 2358.6 2410.8 2841.0 9499.7 9993.3 10013.4 10369.2 10387.3 1813.8 1853.8 1894.2 1913.0 1916.1 80479.6 96981.0 109420.5 114580.4 120416.0 9438.9 10011.7 10126.8 10224.3 10386.8 12326.8 12418.4 12472.9 12481.8 12490.9 7564.1 7887.3 8081.2 8098.6 8171.4 6839.7 7209.4 8235.1 8276.8 8330.7 3892.8 3917.6 3920.3 3961.0 3965.9 1714.5 1870.2 1918.3 1939.0 1989.7 829.2 913.7 951.4 997.4 1029.2 17852.6 20908.2 20980.7 21006.4 21238.9 910.3 910.9 946.2 960.5 961.6 8862.8 9419.9 10265.4 10717.1 11322.9 624943.0 630986.0 633432.0 634369.0 645229.0 106306.9 137224.0 138896.3 140418.0 147762.3 887.5 969.9 991.1 991.1 991.1 18787.5 18900.8 18940.6 18962.5 19118.7 53448.0 56039.0 56460.7 56700.0 57795.4 79299.0 79531.6 79979.8 81275.8 81324.6 7943.6 8008.7 8093.8 8171.6 8353.4 12020.2 12496.3 12759.6 12764.4 12819.7 10605.7 10951.1 11043.0 11075.6 11144.9 9541.7 10663.8 10725.5 10814.3 11018.7 11114.4 11222.0 11382.2 11430.1 11439.8 4882.4 7974.4 8001.0 8208.4 8240.7 9049.3 11715.7 11722.6 11804.7 11841.2 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5732.07 Median:2726.61 stddev:3245.78 (119.0%) stddev-sans-first:3238.53 04:29:48 INFO - PROCESS | 1669 | Values: 1397.0 1549.1 1567.5 1821.0 1978.3 7909.7 8371.2 8379.0 8385.4 8393.7 8584.7 8910.9 8911.6 9014.2 9016.2 2024.3 2298.0 2386.7 2387.4 2389.3 2146.5 2324.8 2361.5 2394.3 2414.9 2368.6 2399.6 2401.3 2404.0 2406.4 2353.0 2356.1 2390.9 2393.8 2412.6 2091.4 2403.0 2431.2 2432.3 2435.2 8679.2 8990.7 8992.3 9002.1 9025.9 4187.0 5251.2 5441.6 5462.2 5470.2 3473.4 3585.7 3609.8 3685.5 3694.1 1074.2 1096.1 1102.7 1103.7 1115.5 9999.2 12135.9 12165.9 12177.3 12203.8 4208.7 4556.1 4676.5 4692.3 4692.7 9340.0 9340.2 9343.3 9350.4 9359.6 5246.2 5313.0 5316.0 5316.9 5319.4 4029.0 4436.1 4572.1 4897.0 4958.2 3801.5 3826.4 3851.8 3876.7 3908.5 1142.4 1144.5 1147.3 1159.4 1164.8 643.7 813.8 924.6 954.7 984.3 5875.2 5933.9 6019.7 6091.0 6191.6 473.7 479.8 492.8 495.0 590.0 3899.4 3916.4 4031.8 4091.2 4113.1 10484.7 10704.4 10891.3 10901.8 10902.7 10345.6 10757.7 10771.0 10860.6 10961.8 8149.2 8296.7 8372.2 8391.0 8392.0 5982.7 6144.1 6151.8 6207.3 6218.7 9994.4 10256.3 10263.2 10267.7 10282.3 9904.0 10197.5 10309.9 10464.2 10477.7 7598.5 7659.6 7735.4 7758.7 7787.5 8210.1 8348.5 8375.2 8388.9 8444.0 7495.0 7592.8 7610.0 7654.8 7686.8 7447.6 7457.3 7514.9 7550.7 7620.6 7023.6 7165.6 7170.2 7171.1 7173.1 4993.3 5046.5 5077.9 5087.0 5165.7 5022.2 5088.0 5132.7 5242.4 5247.1 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4322.47 Median:4801.41 stddev:1322.26 (27.5%) stddev-sans-first:1290.96 04:29:48 INFO - PROCESS | 1669 | Values: 295.4 304.2 311.6 313.1 317.1 4934.9 5218.6 5236.4 5283.2 5307.5 4037.7 4329.8 4911.6 5030.5 5181.8 3670.6 3720.2 3996.3 4020.3 4042.4 3431.8 3479.2 4022.3 4026.3 4081.9 3206.7 3720.9 3870.4 3961.2 4015.3 3466.9 3934.8 3965.4 3979.7 3999.0 2933.5 3021.3 3305.8 3414.8 3496.2 4707.7 4711.1 4815.8 4998.5 5044.2 4428.1 4456.9 4476.6 4483.4 4504.7 4285.5 5035.0 5035.6 5112.4 5509.5 3227.1 3408.1 3676.3 3708.7 3755.9 5379.4 5456.3 5718.3 5736.4 5807.8 3752.8 3765.8 3767.6 3800.1 3803.8 3838.4 3888.8 3893.0 3895.8 3905.6 4093.0 4155.4 4211.3 4471.3 4532.7 4792.5 4880.1 4934.5 5096.4 5303.5 1143.1 1312.4 1320.5 1335.4 1339.5 812.9 849.9 867.5 892.7 895.6 4029.9 4290.6 4984.0 5035.6 5056.1 4656.0 4723.5 5127.6 5281.9 5631.4 4550.0 4674.0 4707.0 4996.8 5012.3 2478.6 2548.9 2551.7 2682.9 2922.3 4876.8 5066.6 5074.2 5797.3 5801.4 4890.4 4897.9 4917.9 5207.8 5247.7 4425.4 5748.8 5765.5 5772.7 5830.6 5325.7 5551.3 5556.7 5568.4 5592.0 4561.5 4713.0 5010.8 5336.3 5506.5 5277.3 5760.5 5771.0 5789.1 5799.9 3312.7 3674.2 3725.8 3753.6 3778.9 4810.3 5161.7 5715.5 5737.4 5744.4 4700.9 5100.0 5297.0 5362.5 5514.0 4580.5 4642.6 4882.3 5523.4 5565.2 4789.8 5088.3 5117.4 5239.2 5444.6 4674.0 4855.7 5283.8 5452.7 5474.9 5343.1 5357.8 5369.7 5376.0 5385.2 04:29:48 INFO - PROCESS | 1669 | 04:29:48 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18831.08 Median:24592.10 stddev:25693.32 (104.5%) stddev-sans-first:25729.33 04:29:48 INFO - PROCESS | 1669 | Values: 702.2 733.4 748.7 840.4 846.5 14649.1 15233.5 15251.8 15258.9 16116.0 15805.0 16052.3 16102.2 16124.4 16146.5 627.7 732.0 907.4 909.9 920.3 883.2 913.9 915.7 916.4 919.6 947.9 957.3 958.4 959.2 959.6 747.8 772.0 772.4 951.1 952.4 569.9 570.8 684.3 708.0 708.8 14871.9 14924.6 14949.8 14955.9 14957.3 13060.7 13173.4 13265.9 13316.0 13513.3 12143.3 12431.3 12543.0 12600.9 12878.7 4426.5 4499.2 4574.2 4634.2 4639.4 78878.8 78938.0 79242.4 79289.2 79605.4 10082.3 10302.1 10550.7 10993.8 11012.2 19200.2 20073.2 20117.6 20163.6 20178.1 7804.1 7826.6 7866.6 7872.0 7875.1 4264.1 4405.8 4420.1 4426.8 4512.6 2958.7 3368.8 3475.6 3490.9 3492.4 573.0 656.8 661.2 663.2 673.7 567.3 599.0 600.5 634.5 646.8 9783.9 10243.8 10256.1 10392.3 10412.7 674.4 680.5 694.2 720.2 725.2 4388.0 4450.0 4460.9 4468.5 4611.6 94345.5 96111.0 96362.5 96692.0 96994.0 69198.7 85287.6 85528.7 87018.0 87111.7 74455.5 74666.0 74940.0 75862.6 76791.6 8878.4 8918.6 8922.7 8925.0 9091.0 43183.1 44271.6 44685.0 45361.5 46370.8 43572.0 48853.0 62654.4 63153.0 63657.4 12174.5 12515.9 12614.2 12865.8 13503.2 14012.6 14846.9 14917.2 14977.1 15336.3 12712.1 13340.3 13387.7 13529.9 14068.9 11816.5 13679.4 13790.0 13975.3 13990.9 13229.5 13293.3 13317.7 13446.2 13463.2 11222.2 11253.5 11404.1 11404.2 11655.3 9640.2 10996.5 11140.6 11164.6 11283.7 04:29:48 INFO - PROCESS | 1669 | -------- Summary: end -------- 04:29:48 INFO - PROCESS | 1669 | 04:29:49 INFO - TEST-INFO | 1669: exit 0 04:29:49 INFO - TEST-OK | dromaeo_css | took 1116204ms 04:29:49 INFO - TEST-START | kraken 04:29:49 INFO - operating with platform_type : mac_ 04:29:49 INFO - Initialising browser for kraken test... 04:29:49 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpYSYCvM/profile http://localhost:49275/getInfo.html) 04:29:51 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 04:29:51 INFO - PROCESS | 1676 | colorDepth:24 04:29:51 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 04:29:51 INFO - PROCESS | 1676 | __metrics 04:29:51 INFO - PROCESS | 1676 | 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" 04:29:51 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:29:52 INFO - TEST-INFO | 1676: exit 0 04:29:52 INFO - Browser initialized. 04:29:52 INFO - Running cycle 1/1 for kraken test... 04:29:52 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpYSYCvM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:03 INFO - PROCESS | 1677 | RSS: Main: 241696768 04:30:03 INFO - PROCESS | 1677 | 04:30:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 04:30:07 INFO - PROCESS | 1677 | RSS: Main: 292098048 04:30:07 INFO - PROCESS | 1677 | 04:30:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 04:30:10 INFO - PROCESS | 1677 | RSS: Main: 301748224 04:30:10 INFO - PROCESS | 1677 | 04:30:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 04:30:15 INFO - PROCESS | 1677 | RSS: Main: 388984832 04:30:15 INFO - PROCESS | 1677 | 04:30:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 04:30:17 INFO - PROCESS | 1677 | RSS: Main: 287072256 04:30:17 INFO - PROCESS | 1677 | 04:30:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:30:20 INFO - PROCESS | 1677 | RSS: Main: 291807232 04:30:20 INFO - PROCESS | 1677 | 04:30:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 04:30:23 INFO - PROCESS | 1677 | RSS: Main: 327081984 04:30:23 INFO - PROCESS | 1677 | 04:30:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 04:30:27 INFO - PROCESS | 1677 | RSS: Main: 328097792 04:30:27 INFO - PROCESS | 1677 | 04:30:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 04:30:30 INFO - PROCESS | 1677 | RSS: Main: 328110080 04:30:30 INFO - PROCESS | 1677 | 04:30:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:30:32 INFO - PROCESS | 1677 | RSS: Main: 309424128 04:30:32 INFO - PROCESS | 1677 | 04:30:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:30:35 INFO - PROCESS | 1677 | RSS: Main: 316248064 04:30:35 INFO - PROCESS | 1677 | 04:30:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:30:38 INFO - PROCESS | 1677 | RSS: Main: 320983040 04:30:38 INFO - PROCESS | 1677 | 04:30:41 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:30:41 INFO - PROCESS | 1677 | RSS: Main: 322695168 04:30:41 INFO - PROCESS | 1677 | 04:30:44 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:30:45 INFO - PROCESS | 1677 | RSS: Main: 323252224 04:30:45 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 04:30:47 INFO - PROCESS | 1677 | RSS: Main: 367263744 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | __start_tp_report 04:30:47 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 04:30:47 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 04:30:47 INFO - PROCESS | 1677 | |i|pagename|runs| 04:30:47 INFO - PROCESS | 1677 | |0;ai-astar;113;109;112;115;113;113;110;113;113;113 04:30:47 INFO - PROCESS | 1677 | |1;audio-beat-detection;156;125;121;191;139;109;157;139;175;128 04:30:47 INFO - PROCESS | 1677 | |2;audio-dft;186;184;186;183;183;183;183;184;185;182 04:30:47 INFO - PROCESS | 1677 | |3;audio-fft;77;73;60;105;57;57;63;65;82;57 04:30:47 INFO - PROCESS | 1677 | |4;audio-oscillator;88;88;92;72;70;69;95;70;67;102 04:30:47 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;89;84;106;98;90;87;90;88;73;113 04:30:47 INFO - PROCESS | 1677 | |6;imaging-darkroom;95;95;96;95;96;95;96;96;96;96 04:30:47 INFO - PROCESS | 1677 | |7;imaging-desaturate;85;84;85;85;86;84;85;86;85;85 04:30:47 INFO - PROCESS | 1677 | |8;json-parse-financial;55;56;57;57;54;54;56;55;54;54 04:30:47 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;65;72;71;75;72;97;100;101;82;70 04:30:47 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;87;87;85;83;84;87;84;79;119;101 04:30:47 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;138;146;147;146;140;139;139;134;166;149 04:30:47 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;168;164;179;165;162;164;164;165;167;164 04:30:47 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;66;66;66;67;65;68;64;67;64;66 04:30:47 INFO - PROCESS | 1677 | __end_tp_report 04:30:47 INFO - PROCESS | 1677 | __start_cc_report 04:30:47 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2021 04:30:47 INFO - PROCESS | 1677 | __end_cc_report 04:30:47 INFO - PROCESS | 1677 | __startTimestamp1464867047264__endTimestamp 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | ------- Summary: start ------- 04:30:47 INFO - PROCESS | 1677 | Number of tests: 14 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:1.71 (1.5%) stddev-sans-first:1.80 04:30:47 INFO - PROCESS | 1677 | Values: 113.0 109.0 112.0 115.0 113.0 113.0 110.0 113.0 113.0 113.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:147.50 stddev:25.61 (17.4%) stddev-sans-first:26.80 04:30:47 INFO - PROCESS | 1677 | Values: 156.0 125.0 121.0 191.0 139.0 109.0 157.0 139.0 175.0 128.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.37 (0.7%) stddev-sans-first:1.22 04:30:47 INFO - PROCESS | 1677 | Values: 186.0 184.0 186.0 183.0 183.0 183.0 183.0 184.0 185.0 182.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:69.60 Median:64.00 stddev:15.30 (23.9%) stddev-sans-first:15.99 04:30:47 INFO - PROCESS | 1677 | Values: 77.0 73.0 60.0 105.0 57.0 57.0 63.0 65.0 82.0 57.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:80.00 stddev:12.99 (16.2%) stddev-sans-first:13.55 04:30:47 INFO - PROCESS | 1677 | Values: 88.0 88.0 92.0 72.0 70.0 69.0 95.0 70.0 67.0 102.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:88.50 stddev:11.33 (12.8%) stddev-sans-first:11.97 04:30:47 INFO - PROCESS | 1677 | Values: 89.0 84.0 106.0 98.0 90.0 87.0 90.0 88.0 73.0 113.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:30:47 INFO - PROCESS | 1677 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 04:30:47 INFO - PROCESS | 1677 | Values: 85.0 84.0 85.0 85.0 86.0 84.0 85.0 86.0 85.0 85.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.23 (2.2%) stddev-sans-first:1.30 04:30:47 INFO - PROCESS | 1677 | Values: 55.0 56.0 57.0 57.0 54.0 54.0 56.0 55.0 54.0 54.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:72.00 stddev:13.71 (19.0%) stddev-sans-first:13.34 04:30:47 INFO - PROCESS | 1677 | Values: 65.0 72.0 71.0 75.0 72.0 97.0 100.0 101.0 82.0 70.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:84.50 stddev:11.81 (14.0%) stddev-sans-first:12.48 04:30:47 INFO - PROCESS | 1677 | Values: 87.0 87.0 85.0 83.0 84.0 87.0 84.0 79.0 119.0 101.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:146.00 stddev:8.98 (6.2%) stddev-sans-first:9.23 04:30:47 INFO - PROCESS | 1677 | Values: 138.0 146.0 147.0 146.0 140.0 139.0 139.0 134.0 166.0 149.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:165.00 stddev:4.80 (2.9%) stddev-sans-first:5.05 04:30:47 INFO - PROCESS | 1677 | Values: 168.0 164.0 179.0 165.0 162.0 164.0 164.0 165.0 167.0 164.0 04:30:47 INFO - PROCESS | 1677 | 04:30:47 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.29 (1.9%) stddev-sans-first:1.36 04:30:47 INFO - PROCESS | 1677 | Values: 66.0 66.0 66.0 67.0 65.0 68.0 64.0 67.0 64.0 66.0 04:30:47 INFO - PROCESS | 1677 | -------- Summary: end -------- 04:30:47 INFO - PROCESS | 1677 | 04:30:48 INFO - TEST-INFO | 1677: exit 0 04:30:48 INFO - TEST-OK | kraken | took 59062ms 04:30:48 INFO - SUITE-END | took 1175s 04:30:48 INFO - Completed test suite (00:19:35) 04:30:48 INFO - javascript benchmark 04:30:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3424.0754996151036, 3443.845026973786, 3444.380011597913, 3472.9652703472957, 3519.7712148710348, 5564.972175139119, 5643.435656434354, 5660.801871934483, 5713.885722285552, 5716.6284191527575, 5650.74571644276, 5701.486866182053, 5754.16564598134, 5795.884082318351, 5836.970815145918, 4182.874513764592, 5079.6698214615935, 5141.717205553712, 5142.202958541432, 5227.764750586214, 5238.214267859814, 5251.868703282429, 5262.8684282892855, 5298.894022119555, 5327.946720532784, 5075.984803039407, 5119.641625086246, 5128.71792051437, 5151.536361727425, 5158.458361871987, 5168.22476628505, 5180.430152683201, 5184.922226166611, 5191.377034755854, 5197.558207552363, 5087.974560127176, 5114.974425127888, 5120.308758317617, 5140.1518749406305, 5160.92258616121, 5551.444855514416, 5555.2222688823595, 5616.831495055155, 5644.717764111778, 5717.0, 6171.87656246877, 6269.090981807658, 6276.246850377938, 6281.466075383598, 6296.118543403927, 6241.28225254097, 6249.0626406039, 6323.968380158116, 6344.270408902989, 6348.555601107924, 5628.493435590775, 5681.318241810968, 5719.742611582489, 5788.392218816964, 5801.50687191585, 6211.937880621136, 6245.219347581552, 6264.21697287839, 6265.248170219603, 6266.968665156645, 6142.078688196806, 6160.599561028608, 6162.876742465126, 6171.537134714914, 6180.0, 6096.375503402309, 6161.8459538511, 6243.562950593415, 6244.219472565929, 6304.306526282197, 6207.534434917309, 6220.253569571681, 6226.315105338409, 6226.968865155736, 6242.875142497124, 5970.253718285215, 6229.1590635264565, 6248.625082495065, 6250.15622890913, 6250.406211409909, 4858.0, 4891.461939186686, 4901.995091006335, 4910.189818679878, 4938.456769755324, 4395.099004704028, 4417.182821178092, 4421.5578442156175, 4458.175237580993, 4483.125790470827, 5624.803131890364, 5634.0, 5643.5767317451355, 5646.491815736604, 5689.971550142305, 6092.817215483543, 6159.260888693386, 6185.0, 6241.438270555581, 6278.46633036197, 5377.789997250666, 5643.633163844337, 5646.0683987141665, 5648.15277708347, 5676.716164191774, 4739.454962679267, 5302.363716353985, 5347.197920311906, 5387.0, 5400.189971504227, 6173.660448675366, 6204.410580994799, 6220.626762394271, 6233.065040244, 6267.905981410283, 6089.756409743561, 6277.623342599459, 6285.120083188266, 6290.654014029273, 6310.936890631035, 6321.5258855586935, 6322.589031712924, 6331.493480521639, 6346.809595712136, 6353.0152826312, 4918.975405122951, 6054.061620448838, 6126.914660831389, 6162.198914141133, 6209.875802483834, 6281.214848143982, 6294.874102518015, 6306.747730090745, 6309.053641953744, 6331.2085989251345, 6075.1798507200965, 6095.542834287535, 6115.266168059683, 6142.539309551712, 6150.292716337678, 5841.094630332305, 5902.026165682614, 5908.172855800105, 5970.58205925581, 5992.610480318765, 5530.0, 5972.193753843176, 5993.340732519332, 6017.217761690952, 6051.183090282755, 5953.880922381615, 6124.859813084112, 6128.816135515942, 6129.820301844934, 6130.3369889931655, 5742.712864356849, 5807.448292412214, 5838.532917366685, 5915.0, 5947.167396564398], "value": 5667.948374073284, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.98530044098646, 497.0357506437511, 500.68707058088694, 518.0933366608435, 540.8485624025274, 31425.371492570135, 41873.16253674928, 43218.351724724074, 43364.0, 43382.349264761055, 44371.0, 44428.0, 44436.77781611095, 44452.99999999992, 45334.77332613341, 9461.668841590546, 9660.613575456973, 9692.612295508206, 9692.806143877118, 9699.951500242525, 9524.333296669236, 9670.0, 9671.419714817099, 9688.273379496532, 9714.902850971506, 9669.854952175723, 9692.321537492344, 9706.029397060272, 9706.466144362093, 9714.271429642806, 9691.51542422876, 9694.903050969471, 9702.660406885725, 9716.222702183808, 9803.705888823311, 8063.516189028677, 9507.619695212183, 9571.617135314578, 9576.138147566682, 9592.760180995461, 40894.591054089375, 41228.587714123074, 41246.38130428046, 41290.38064429036, 41596.088273820846, 3568.536090308269, 3914.725969182158, 3946.52641682997, 4149.543550209474, 4370.038591509863, 31363.0, 31482.842585787155, 31530.0, 31995.52006719901, 32310.0, 3223.4197844388, 3258.657840926716, 3286.027711343488, 3300.7689461737687, 3324.7672662913606, 503294.0, 504487.0, 504759.952400475, 505928.99999999633, 506271.0, 63597.04604430937, 63920.680396597716, 64352.67823660852, 64442.677786610766, 64470.35529644643, 34195.829020855235, 35234.64765352365, 65158.67420662866, 65479.345206547325, 65611.67194163999, 16140.435084771976, 16325.428609998593, 16329.101899395648, 16353.83646163547, 16398.180090995404, 11929.403529823474, 12038.81941770874, 12096.697582560506, 12097.153199275966, 12102.515899364103, 16680.666386672197, 19773.406797796088, 19845.908475033724, 19876.801231987494, 20103.597928041356, 7615.961920190474, 8171.591420429074, 8198.590070496452, 8261.339092872557, 8346.457480263764, 884.6505630276005, 965.75856035991, 971.5288085278635, 1044.237706474278, 1158.623447379605, 29559.817607295896, 30685.539716904263, 31043.534346984812, 31476.213094672818, 31775.364492710018, 971.5870754929127, 1141.8401423800674, 1155.3010428690689, 1196.5094311332311, 1200.1598880783486, 15710.057396556243, 16017.279222435018, 16041.919790401207, 16374.345026198818, 16433.671326573403, 692975.0, 695161.5241923758, 697855.0, 698750.0, 698857.0, 61905.071423928675, 62336.0, 62429.0, 62430.0635490468, 62624.68687656624, 1281.9935900320625, 1286.7233544787916, 1409.823772028517, 1434.3545404567985, 1456.2281990545027, 26678.0, 31979.68020319767, 32135.67864321327, 32275.35449291048, 32736.83631581827, 53986.73006634942, 54077.0, 55193.89612207653, 55219.0, 55291.44708552863, 617565.0, 633094.0, 633332.0, 635488.8225558843, 637299.0, 35879.46180807342, 37289.440658389605, 45349.31976020296, 46147.0, 47414.76292618515, 49442.25836612382, 50208.0, 50562.747186263834, 50742.492575073775, 50899.0, 34486.655133448345, 38566.0, 39936.4009539863, 41387.75836724903, 41396.793016034724, 40564.39153412642, 40673.32804018862, 41067.383989239584, 41392.79303603463, 41864.372034420114, 39494.01264968399, 40192.39711404273, 40239.3904243827, 40291.798541007105, 40635.390469143575, 31293.0, 32078.35843283168, 35143.12834091002, 36460.0, 36538.26923461569, 25456.109036184385, 25611.0, 26018.797836005237, 26174.59346256946, 26333.60499592546], "value": 20847.25464624759, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.82244765379045, 374.18987891215505, 375.8271195250184, 389.71550767939385, 403.63471058691874, 19745.11146998385, 19783.40649780507, 20860.791392086074, 21137.894310528427, 21161.894190529063, 9927.900720992788, 12196.695082622917, 15963.0, 19416.737912035744, 20988.950552472354, 5164.819231326914, 5165.8450246492475, 5273.578113750891, 5368.865778355534, 5380.757865896025, 3826.8469261229516, 4146.129312844307, 4396.20868243716, 6651.800445986604, 6691.895837186858, 6619.139511863451, 6638.634875081869, 6645.700943457532, 6655.101561289212, 6659.300773418794, 6293.402126797947, 6480.31956644555, 6506.3818937200895, 6596.835079123036, 6609.702563384635, 3695.7597756146033, 4881.536254055859, 5413.296271484721, 5658.8019419320535, 5747.798827041033, 13440.260785656785, 13524.0, 13549.661258468519, 20158.0, 25718.87140564285, 2119.4595378178465, 2342.5783358995373, 2358.6344116661944, 2410.8191885608476, 2840.957385639217, 9499.667511637059, 9993.25050621199, 10013.449260290681, 10369.22230832683, 10387.27289089764, 1813.839142948514, 1853.7868145163343, 1894.232102875037, 1912.9521761955923, 1916.1377380178976, 80479.5976020122, 96981.0, 109420.4528977358, 114580.42709786481, 120416.0, 9438.90561094394, 10011.749706257257, 10126.848097278547, 10224.284300098921, 10386.844197337046, 12326.75346493065, 12418.441170147365, 12472.937635311946, 12481.812772808415, 12490.937545312216, 7564.130125035581, 7887.250711182429, 8081.191880811872, 8098.554579498066, 8171.387145964077, 6839.726410943506, 7209.423246140296, 8235.094139644634, 8276.793080173044, 8330.666773329, 3892.8442862285756, 3917.5690674025836, 3920.31394505966, 3960.980195099006, 3965.8810235692977, 1714.4685147604282, 1870.2310838142503, 1918.2518817661123, 1938.9709154362695, 1989.671704168796, 829.2246749289434, 913.7030465098869, 951.378406292214, 997.436448406648, 1029.2075102171436, 17852.642947141245, 20908.163673453197, 20980.685289720666, 21006.369808905452, 21238.89380553087, 910.3127139010005, 910.899801021887, 946.1768261612441, 960.4525420510242, 961.6201600367815, 8862.815615484325, 9419.858702119474, 10265.435401052864, 10717.14262858985, 11322.92432218938, 624943.0, 630986.0, 633432.0, 634369.0, 645229.0, 106306.9369306297, 137224.0, 138896.30551847175, 140418.0, 147762.26118869553, 887.4808237181321, 969.8690676758688, 991.0832479956011, 991.1228562721899, 991.1476130527741, 18787.530311741768, 18900.810991889906, 18940.621187576446, 18962.52593685169, 19118.713219302, 53448.0, 56039.0, 56460.71769641126, 56700.0, 57795.42204577901, 79299.0, 79531.60234198792, 79979.80030299434, 81275.78086328828, 81324.59337703274, 7943.602819859099, 8008.679652813822, 8093.797655058671, 8171.632276547451, 8353.415260931919, 12020.218685785396, 12496.250224986166, 12759.553415630407, 12764.361781911053, 12819.679508012374, 10605.666753314259, 10951.069159121564, 11043.0, 11075.612353567587, 11144.888551114385, 9541.713748587554, 10663.840042399526, 10725.463726813472, 10814.29707069038, 11018.669439916815, 11114.444277785917, 11221.990020898158, 11382.15431201065, 11430.14273929442, 11439.828402574134, 4882.414110306786, 7974.38536759163, 8001.0, 8208.425410221227, 8240.711575094843, 9049.276057915482, 11715.707107322385, 11722.648320550397, 11804.70488237801, 11841.171118021655], "value": 9194.21213611762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.0, 1549.0628169957176, 1567.5297410776768, 1820.9544761380964, 1978.3075923426802, 7909.6836126554945, 8371.204735550129, 8378.958105209473, 8385.413021088527, 8393.70622028229, 8584.699535516258, 8910.866337004934, 8911.643534258621, 9014.233790127848, 9016.233620142279, 2024.2611446821877, 2297.977400056971, 2386.6658667786437, 2387.3912152401113, 2389.2951579284154, 2146.5384942237415, 2324.790768830805, 2361.53949979754, 2394.2577800881695, 2414.927552173429, 2368.5854975379325, 2399.5800734871414, 2401.2916189724074, 2403.9759602404015, 2406.4104294447798, 2352.9529409411803, 2356.057576969209, 2390.8804559771943, 2393.7845593896545, 2412.6260429633435, 2091.4248581640018, 2402.9639555406684, 2431.222008957134, 2432.2581612608146, 2435.2329016359968, 8679.17547832955, 8990.730278091667, 8992.280617550581, 9002.05478424769, 9025.864612030824, 4186.979065104655, 5251.212318152269, 5441.59188060893, 5462.180672899057, 5470.206820011076, 3473.426884564044, 3585.731070169748, 3609.783412995229, 3685.539307586552, 3694.058015206132, 1074.1943542343242, 1096.1395304685784, 1102.728379538154, 1103.6799328194813, 1115.5147510832733, 9999.150072243796, 12135.93932030343, 12165.93917030418, 12177.269363838199, 12203.755924881452, 4208.72643278192, 4556.111558246176, 4676.485586585472, 4692.296155576691, 4692.700576717452, 9340.019297973751, 9340.159385655188, 9343.2525397968, 9350.438973661601, 9359.578818953163, 5246.186841039645, 5313.0, 5315.973420132875, 5316.946830531645, 5319.414864364917, 4029.0, 4436.112777444524, 4572.0627271409285, 4896.975515122402, 4958.206686930074, 3801.5248093988253, 3826.3686491728554, 3851.768893866321, 3876.7480113792512, 3908.4528166056775, 1142.4287856071965, 1144.49070163778, 1147.3345459633392, 1159.4144956796763, 1164.8078067118831, 643.6974621927686, 813.8494378539989, 924.5562130177418, 954.71358592422, 984.3208186351395, 5875.20684707559, 5933.881322373529, 6019.69901504923, 6091.0, 6191.597546159485, 473.6707987948412, 479.8172504775745, 492.8151943021367, 495.0149203085833, 590.0352922970974, 3899.3566061599518, 3916.373380259202, 4031.778252196157, 4091.2226676931286, 4113.136241389342, 10484.685459436228, 10704.411257380763, 10891.292066015683, 10901.83647245292, 10902.672919812418, 10345.586176553004, 10757.731056723644, 10770.976757208053, 10860.565577376974, 10961.83557246642, 8149.225823546753, 8296.668133274601, 8372.204640559137, 8390.958045209734, 8391.958040209882, 5982.7307771149535, 6144.078388241621, 6151.753929842936, 6207.317195108444, 6218.689065546795, 9994.400335979864, 10256.333338332985, 10263.23025773055, 10267.691969240936, 10282.331648442827, 9903.950480247553, 10197.541110650187, 10309.896901031194, 10464.162866970777, 10477.738056548647, 7598.468107232441, 7659.578723170056, 7735.381169506539, 7758.650860711395, 7787.532748035136, 8210.055843578066, 8348.540830254151, 8375.16248375182, 8388.916110838814, 8443.95778021106, 7495.0, 7592.772216833504, 7609.961950190435, 7654.823248352094, 7686.769396918102, 7447.590382529013, 7457.328840404172, 7514.887276690744, 7550.697972081275, 7620.618969051636, 7023.648817558999, 7165.605891676008, 7170.175429825428, 7171.1394632644415, 7173.1392232932385, 4993.251012348177, 5046.4701206374275, 5077.898442031064, 5086.974565127299, 5165.715885626177, 5022.246663000433, 5087.974560127176, 5132.666376685504, 5242.397124330599, 5247.134222853339], "value": 4522.736323488898, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.4032853635655, 304.2090564532216, 311.56536631399194, 313.0811069510984, 317.08520917154016, 4934.851954441364, 5218.556422704065, 5236.371635403752, 5283.233931079991, 5307.548858347045, 4037.7173597848164, 4329.783510824462, 4911.557959783619, 5030.522100400456, 5181.844544663666, 3670.6145854685274, 3720.2373513429748, 3996.2806694794926, 4020.2763502569524, 4042.43405923171, 3431.811250381228, 3479.234568394949, 4022.3162062449455, 4026.3089502409057, 4081.9183616327655, 3206.679332066798, 3720.9069773255615, 3870.4000879863524, 3961.1681546875193, 4015.317396042665, 3466.94799578004, 3934.783586902719, 3965.405189221611, 3979.721419500622, 3999.0204848599838, 2933.5159698649695, 3021.2597913511113, 3305.834708264601, 3414.846331915045, 3496.160921378876, 4707.693999889996, 4711.057788442326, 4815.783289751969, 4998.500449865026, 5044.218146187347, 4428.0922410906005, 4456.866294011184, 4476.61948734358, 4483.39474170986, 4504.662150338738, 4285.507166675841, 5034.974825125888, 5035.622328325354, 5112.386513618352, 5509.5041446270025, 3227.080282119585, 3408.0968543336035, 3676.283124790667, 3708.6847617952235, 3755.8873233802756, 5379.435159308295, 5456.345238571397, 5718.285214348165, 5736.447280959975, 5807.793038645372, 3752.8311225994894, 3765.755225910307, 3767.6420740029653, 3800.0619683961377, 3803.801802432225, 3838.4302598583586, 3888.766673999569, 3892.980535097363, 3895.824902704287, 3905.5703872573995, 4092.9590704093175, 4155.439015732898, 4211.347241177622, 4471.28459446487, 4532.70537415067, 4792.496787837296, 4880.072786170616, 4934.4572097069295, 5096.413912400121, 5303.488505775836, 1143.0969534067992, 1312.3766211049865, 1320.5312114199512, 1335.3523541082568, 1339.5110784563565, 812.8740045292989, 849.8512760266929, 867.5468590111537, 892.7321803459066, 895.5611750242465, 4029.911923780563, 4290.635295999875, 4984.028114517707, 5035.571976381975, 5056.064628043824, 4655.95344046562, 4723.527647235249, 5127.564157046693, 5281.894362112813, 5631.408702086222, 4550.0, 4673.976630116896, 4706.976465117653, 4996.825111121093, 5012.323336349619, 2478.5662509060844, 2548.8980440782534, 2551.6937967444023, 2682.906098286551, 2922.327864591156, 4876.756427111064, 5066.569341605931, 5074.188129899198, 5797.304323481125, 5801.448862358069, 4890.388701412324, 4897.9020419592125, 4917.926231106536, 5207.843764687065, 5247.658902171423, 4425.402570652921, 5748.798792042342, 5765.4811067004175, 5772.740226689809, 5830.62100963436, 5325.707086110301, 5551.333839939233, 5556.749946252347, 5568.415316391883, 5592.008275572549, 4561.475430325555, 4712.986707857827, 5010.7745151469, 5336.345732822837, 5506.504414602705, 5277.313949186581, 5760.539156867357, 5771.0, 5789.073748200268, 5799.913001305068, 3312.668733126668, 3674.1812802662853, 3725.7578257413184, 3753.6434038766274, 3778.9433158503193, 4810.326554282473, 5161.690298582097, 5715.456826656862, 5737.397573254915, 5744.425557444222, 4700.858974230779, 5099.974500127476, 5297.0, 5362.490563396471, 5513.972430137824, 4580.541945805393, 4642.628589712883, 4882.316475693477, 5523.364813046391, 5565.16522521625, 4789.760511974457, 5088.338515992898, 5117.411497677815, 5239.24031015493, 5444.646098003617, 4673.976630116964, 4855.72238738857, 5283.841484755463, 5452.727363631723, 5474.8905021898545, 5343.0649636314265, 5357.785688572413, 5369.704666243394, 5375.962581141643, 5385.219143224289], "value": 3908.632347824442, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.1994925784605, 733.4242619543659, 748.685552068131, 840.4453060979755, 846.4751853850612, 14649.121052736844, 15233.542993710178, 15251.847481525196, 15258.923705381472, 16116.0, 15804.972676776002, 16052.277647505862, 16102.19489025547, 16124.355025798954, 16146.515604531884, 627.6610630259654, 732.0044739154772, 907.4464576608264, 909.9272058235325, 920.3327587499076, 883.2360008592553, 913.9497327646977, 915.7207051849183, 916.4043371808311, 919.586186216202, 947.947862867542, 957.3394357893067, 958.3674774648733, 959.2278216036088, 959.6113574002541, 747.8429529798749, 771.9922800772033, 772.3782355204058, 951.1439704266147, 952.4095061062117, 569.9316082070137, 570.848725087852, 684.2712511175632, 707.9823004424879, 708.8050786033831, 14871.925640371837, 14924.62688432787, 14949.775753363709, 14955.850441495664, 14957.252137393087, 13060.738785224243, 13173.407196676173, 13265.86734132656, 13316.0, 13513.324333783272, 12143.332116733576, 12431.316277604728, 12543.0, 12600.873991260154, 12878.678033049155, 4426.490953540352, 4499.208343114573, 4574.199515084847, 4634.189016922025, 4639.39687840582, 78878.81681774721, 78938.0, 79242.41515169664, 79289.20710792876, 79605.42762006662, 10082.29423940317, 10302.124319432782, 10550.736231594117, 10993.780124397626, 11012.17408694351, 19200.231990720495, 20073.197072117244, 20117.597648046958, 20163.596728065357, 20178.091985860672, 7804.102528209215, 7826.647800848976, 7866.567338796421, 7872.0, 7875.054993400829, 4264.104538047045, 4405.84579539721, 4420.093880754438, 4426.764686941133, 4512.571305725951, 2958.689337619519, 3368.831558422069, 3475.6350583188905, 3490.947635785465, 3492.371373152857, 572.9799457018985, 656.7602824968948, 661.2263651527628, 663.2372771312932, 673.7473447457204, 567.2823877794593, 599.02060131685, 600.5495878091432, 634.5336177909234, 646.8221239159269, 9783.853242201372, 10243.846342304721, 10256.07695307426, 10392.324498907547, 10412.739681508023, 674.3829396102511, 680.5406350713348, 694.207520581469, 720.1538192623647, 725.1904615658966, 4387.956120438818, 4450.020661877097, 4460.888477788081, 4468.4637843458995, 4611.5619016193405, 94345.5282723582, 96111.0, 96362.51818741002, 96692.0, 96994.0, 69198.65400673065, 85287.57356213179, 85528.71706924401, 87018.0, 87111.69332460019, 74455.5108897819, 74666.0, 74940.0, 75862.62068689622, 76791.61604192056, 8878.378513503993, 8918.598663060047, 8922.73231803047, 8924.955375223082, 9090.954545227232, 43183.13633727245, 44271.58062080916, 44685.0, 45361.54638453573, 46370.768146159055, 43572.0, 48853.0, 62654.37345626485, 63153.0, 63657.36342636514, 12174.513019479122, 12515.937420312841, 12614.180078294881, 12865.807012894627, 13503.189808611514, 14012.64968375758, 14846.851531484546, 14917.179555124572, 14977.1013739176, 15336.309866055832, 12712.050453039616, 13340.332983350987, 13387.665308367368, 13529.86470135286, 14068.859311407165, 11816.527338906692, 13679.384427700976, 13790.0, 13975.30123493801, 13990.860091398956, 13229.47082116743, 13293.26887021223, 13317.733645327233, 13446.193228405935, 13463.192208467523, 11222.158338124837, 11253.549858005916, 11404.132580749236, 11404.201705880509, 11655.30068195911, 9640.163027772429, 10996.505157268102, 11140.610078647209, 11164.553417863519, 11283.717907052389], "value": 7353.373292549525, "unit": "score"}], "name": "dromaeo_css", "value": 7216.478049416837, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 112.0, 115.0, 113.0, 113.0, 110.0, 113.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 121.0, 191.0, 139.0, 109.0, 157.0, 139.0, 175.0, 128.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 186.0, 183.0, 183.0, 183.0, 183.0, 184.0, 185.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 60.0, 105.0, 57.0, 57.0, 63.0, 65.0, 82.0, 57.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 72.0, 70.0, 69.0, 95.0, 70.0, 67.0, 102.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 106.0, 98.0, 90.0, 87.0, 90.0, 88.0, 73.0, 113.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 86.0, 84.0, 85.0, 86.0, 85.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 57.0, 54.0, 54.0, 56.0, 55.0, 54.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 71.0, 75.0, 72.0, 97.0, 100.0, 101.0, 82.0, 70.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 83.0, 84.0, 87.0, 84.0, 79.0, 119.0, 101.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 146.0, 147.0, 146.0, 140.0, 139.0, 139.0, 134.0, 166.0, 149.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 179.0, 165.0, 162.0, 164.0, 164.0, 165.0, 167.0, 164.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 65.0, 68.0, 64.0, 67.0, 64.0, 66.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1465.4, "alertThreshold": 2.0}]} 04:30:48 INFO - Return code: 0 04:30:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:30:48 ERROR - # TBPL SUCCESS # 04:30:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:48 INFO - 2016-06-02 04:30:48.585 screenresolution[1682:9799] starting screenresolution argv=screenresolution get 04:30:48 INFO - 2016-06-02 04:30:48.587 screenresolution[1682:9799] Display 0: 1600x1200x32@60 04:30:48 INFO - 2016-06-02 04:30:48.593 screenresolution[1683:9802] starting screenresolution argv=screenresolution list 04:30:48 INFO - Available Modes on Display 0 04:30:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:48 INFO - Intel Iris: 04:30:48 INFO - Chipset Model: Intel Iris 04:30:48 INFO - Type: GPU 04:30:48 INFO - Bus: Built-In 04:30:48 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:48 INFO - Vendor: Intel (0x8086) 04:30:48 INFO - Device ID: 0x0a2e 04:30:48 INFO - Revision ID: 0x0009 04:30:48 INFO - Displays: 04:30:48 INFO - Display: 04:30:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:48 INFO - Display Serial Number: 1600x1200 60 04:30:48 INFO - Main Display: Yes 04:30:48 INFO - Mirror: Off 04:30:48 INFO - Online: Yes 04:30:48 INFO - Rotation: Supported 04:30:48 INFO - Return code: 0 04:30:48 INFO - Running post-action listener: _resource_record_post_action 04:30:48 INFO - ##### 04:30:48 INFO - ##### Finished run-tests step (success) 04:30:48 INFO - ##### 04:30:48 INFO - Running post-run listener: _resource_record_post_run 04:30:48 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36890112; Write bytes: 697370624; Read time: 245; Write time: 1918 04:30:48 INFO - TinderboxPrint: CPU usage
53.8% 04:30:48 INFO - TinderboxPrint: I/O read bytes / time
36,890,112 / 245 04:30:48 INFO - TinderboxPrint: I/O write bytes / time
697,370,624 / 1,918 04:30:48 INFO - TinderboxPrint: CPU idle
2,242.7 (46.2%) 04:30:48 INFO - TinderboxPrint: CPU system
974.2 (20.1%) 04:30:48 INFO - TinderboxPrint: CPU user
1,632.7 (33.7%) 04:30:48 INFO - TinderboxPrint: Swap in / out
435,752,960 / 0 04:30:48 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396632576; Write bytes: 386074624; Read time: 30374; Write time: 800 04:30:48 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29781504; Write bytes: 293740544; Read time: 181; Write time: 1074 04:30:49 INFO - Running post-run listener: _upload_blobber_files 04:30:49 INFO - Blob upload gear active. 04:30:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:30:49 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 04:30:49 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'] 04:30:49 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 04:30:49 INFO - (blobuploader) - INFO - Open directory for files ... 04:30:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:30:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:30:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:50 INFO - (blobuploader) - INFO - Done attempting. 04:30:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:30:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:30:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:51 INFO - (blobuploader) - INFO - Done attempting. 04:30:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:30:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:30:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:30:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:30:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:30:55 INFO - (blobuploader) - INFO - Done attempting. 04:30:55 INFO - (blobuploader) - INFO - Iteration through files over. 04:30:55 INFO - Return code: 0 04:30:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:30:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:30:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d3d92a082b636307726927d6b5241c95b8699bbfbc387555051c816e8ff1d53d9c3f2b2b8d467e3ab4c1cc05d7ad1ffcc2d2b25f98f1b44e29eb212124d8fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/363fce56c72ba086d274ee0e89191fa06f3b47f3789f5e7325d1e2d0cfc7a996a8af47afe2d6440277fae70b56e2b5bc111a213dabd4f666d6144cd6452d88e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a63adf1dd7dafee5c2a1c8940779f1dcc7f7505f1c20376933d77affa91273a1ee5bf3a50f700df8ac2b107c08810d7bcb3df0cb018cb95028a3f1587c4019d"} 04:30:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:30:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:30:55 INFO - Contents: 04:30:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8d3d92a082b636307726927d6b5241c95b8699bbfbc387555051c816e8ff1d53d9c3f2b2b8d467e3ab4c1cc05d7ad1ffcc2d2b25f98f1b44e29eb212124d8fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/363fce56c72ba086d274ee0e89191fa06f3b47f3789f5e7325d1e2d0cfc7a996a8af47afe2d6440277fae70b56e2b5bc111a213dabd4f666d6144cd6452d88e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a63adf1dd7dafee5c2a1c8940779f1dcc7f7505f1c20376933d77affa91273a1ee5bf3a50f700df8ac2b107c08810d7bcb3df0cb018cb95028a3f1587c4019d"} 04:30:55 INFO - Running post-run listener: copy_logs_to_upload_dir 04:30:55 INFO - Copying logs to upload dir... 04:30:55 INFO - mkdir: /builds/slave/test/build/upload/logs 04:30:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.408977 ========= master_lag: 1.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-06-02 04:30:56.703579) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 04:30:56.707577) ========= 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.2UYx0xx4dD/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.wd3MkppBmf/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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/8d3d92a082b636307726927d6b5241c95b8699bbfbc387555051c816e8ff1d53d9c3f2b2b8d467e3ab4c1cc05d7ad1ffcc2d2b25f98f1b44e29eb212124d8fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/363fce56c72ba086d274ee0e89191fa06f3b47f3789f5e7325d1e2d0cfc7a996a8af47afe2d6440277fae70b56e2b5bc111a213dabd4f666d6144cd6452d88e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a63adf1dd7dafee5c2a1c8940779f1dcc7f7505f1c20376933d77affa91273a1ee5bf3a50f700df8ac2b107c08810d7bcb3df0cb018cb95028a3f1587c4019d"} build_url:https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011062 build_url: 'https://queue.taskcluster.net/v1/task/Zh-mARNqTIW1perE1UJgZg/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/8d3d92a082b636307726927d6b5241c95b8699bbfbc387555051c816e8ff1d53d9c3f2b2b8d467e3ab4c1cc05d7ad1ffcc2d2b25f98f1b44e29eb212124d8fd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/363fce56c72ba086d274ee0e89191fa06f3b47f3789f5e7325d1e2d0cfc7a996a8af47afe2d6440277fae70b56e2b5bc111a213dabd4f666d6144cd6452d88e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a63adf1dd7dafee5c2a1c8940779f1dcc7f7505f1c20376933d77affa91273a1ee5bf3a50f700df8ac2b107c08810d7bcb3df0cb018cb95028a3f1587c4019d"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 04:30:56.798724) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:30:56.799063) ========= 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.2UYx0xx4dD/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.wd3MkppBmf/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.005148 ========= master_lag: 0.48 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 04:30:57.280723) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 04:30:57.281044) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 04:30:57.713917) ========= ========= Total master_lag: 2.13 =========