builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0090 starttime: 1463483412.05 results: success (0) buildid: 20160517021529 builduid: 1251024ad50a453ab548e66c29a31f54 revision: 92b07e6e84bf828040ac5231eafea31c843e0472 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.048419) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.048903) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.049234) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.069595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.070304) ========= 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.wpGx8GQsrP/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.2MghzUNj2T/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-17 04:10:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-05-17 04:10:12 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095960 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.191957) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.192733) ========= 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.wpGx8GQsrP/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.2MghzUNj2T/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.027710 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:12.243584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 04:10:12.244416) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 92b07e6e84bf828040ac5231eafea31c843e0472 --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 92b07e6e84bf828040ac5231eafea31c843e0472 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wpGx8GQsrP/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.2MghzUNj2T/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-17 04:10:12,319 truncating revision to first 12 chars 2016-05-17 04:10:12,319 Setting DEBUG logging. 2016-05-17 04:10:12,319 attempt 1/10 2016-05-17 04:10:12,319 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/92b07e6e84bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 04:10:13,285 unpacking tar archive at: fx-team-92b07e6e84bf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.216531 ========= master_lag: 1.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 04:10:14.535464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:14.535774) ========= script_repo_revision: 92b07e6e84bf828040ac5231eafea31c843e0472 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:14.536188) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:14.536495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 04:10:14.564104) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-17 04:10:14.564417) ========= /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.wpGx8GQsrP/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.2MghzUNj2T/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:10:14 INFO - MultiFileLogger online at 20160517 04:10:14 in /builds/slave/test 04:10:14 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:10:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:10:14 INFO - {'append_to_log': False, 04:10:14 INFO - 'base_work_dir': '/builds/slave/test', 04:10:14 INFO - 'blob_upload_branch': 'Fx-Team', 04:10:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:10:14 INFO - 'branch': 'Fx-Team', 04:10:14 INFO - 'buildbot_json_path': 'buildprops.json', 04:10:14 INFO - 'config_files': ('talos/mac_config.py',), 04:10:14 INFO - 'default_actions': ('clobber', 04:10:14 INFO - 'read-buildbot-config', 04:10:14 INFO - 'download-and-extract', 04:10:14 INFO - 'populate-webroot', 04:10:14 INFO - 'create-virtualenv', 04:10:14 INFO - 'install', 04:10:14 INFO - 'run-tests'), 04:10:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:10:14 INFO - 'download_minidump_stackwalk': True, 04:10:14 INFO - 'download_symbols': 'ondemand', 04:10:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:10:14 INFO - 'tooltool.py': '/tools/tooltool.py', 04:10:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:10:14 INFO - '/tools/misc-python/virtualenv.py')}, 04:10:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:10:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:10:14 INFO - 'installer_path': 'installer.exe', 04:10:14 INFO - 'log_level': 'info', 04:10:14 INFO - 'log_name': 'talos', 04:10:14 INFO - 'log_to_console': True, 04:10:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:10:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:10:14 INFO - 'opt_config_files': (), 04:10:14 INFO - 'pip_index': False, 04:10:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:10:14 INFO - 'cmd': ('bash', 04:10:14 INFO - '-c', 04:10:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:10:14 INFO - 'enabled': True, 04:10:14 INFO - 'halt_on_failure': False, 04:10:14 INFO - 'name': 'check_screen_resolution'},), 04:10:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:10:14 INFO - 'cmd': ('bash', 04:10:14 INFO - '-c', 04:10:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:10:14 INFO - 'enabled': True, 04:10:14 INFO - 'halt_on_failure': False, 04:10:14 INFO - 'name': 'check_screen_resolution'},), 04:10:14 INFO - 'run_cmd_checks_enabled': True, 04:10:14 INFO - 'sps_profile': False, 04:10:14 INFO - 'sps_profile_interval': 0, 04:10:14 INFO - 'suite': 'dromaeojs', 04:10:14 INFO - 'system_bits': '32', 04:10:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:10:14 INFO - 'title': 't-yosemite-r7-0090', 04:10:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:10:14 INFO - 'use_talos_json': True, 04:10:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:10:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:10:14 INFO - 'work_dir': 'build'} 04:10:14 INFO - ##### 04:10:14 INFO - ##### Running clobber step. 04:10:14 INFO - ##### 04:10:14 INFO - Running pre-action listener: _resource_record_pre_action 04:10:14 INFO - Running main action method: clobber 04:10:14 INFO - rmtree: /builds/slave/test/build 04:10:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:10:16 INFO - Running post-action listener: _resource_record_post_action 04:10:16 INFO - ##### 04:10:16 INFO - ##### Finished clobber step (success) 04:10:16 INFO - ##### 04:10:16 INFO - ##### 04:10:16 INFO - ##### Running read-buildbot-config step. 04:10:16 INFO - ##### 04:10:16 INFO - Running pre-action listener: _resource_record_pre_action 04:10:16 INFO - Running main action method: read_buildbot_config 04:10:16 INFO - Using buildbot properties: 04:10:16 INFO - { 04:10:16 INFO - "project": "", 04:10:16 INFO - "product": "firefox", 04:10:16 INFO - "script_repo_revision": "production", 04:10:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:10:16 INFO - "repository": "", 04:10:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:10:16 INFO - "buildid": "20160517021529", 04:10:16 INFO - "pgo_build": "False", 04:10:16 INFO - "basedir": "/builds/slave/test", 04:10:16 INFO - "buildnumber": 11, 04:10:16 INFO - "slavename": "t-yosemite-r7-0090", 04:10:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:10:16 INFO - "platform": "yosemite_r7", 04:10:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:10:16 INFO - "branch": "fx-team", 04:10:16 INFO - "repo_path": "integration/fx-team", 04:10:16 INFO - "revision": "92b07e6e84bf828040ac5231eafea31c843e0472", 04:10:16 INFO - "stage_platform": "macosx64", 04:10:16 INFO - "builduid": "1251024ad50a453ab548e66c29a31f54", 04:10:16 INFO - "slavebuilddir": "test" 04:10:16 INFO - } 04:10:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:10:16 INFO - Running post-action listener: _resource_record_post_action 04:10:16 INFO - ##### 04:10:16 INFO - ##### Finished read-buildbot-config step (success) 04:10:16 INFO - ##### 04:10:16 INFO - ##### 04:10:16 INFO - ##### Running download-and-extract step. 04:10:16 INFO - ##### 04:10:16 INFO - Running pre-action listener: _resource_record_pre_action 04:10:16 INFO - Running main action method: download_and_extract 04:10:16 INFO - mkdir: /builds/slave/test/build/tests 04:10:16 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:16 INFO - https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:10:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:10:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:10:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:17 INFO - Downloaded 1199 bytes. 04:10:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:10:17 INFO - Using the following test package requirements: 04:10:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:10:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:10:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:10:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'jsshell-mac.zip'], 04:10:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:10:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:10:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:10:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:10:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:10:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:10:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:10:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 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 - https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:10:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:10:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:10:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:19 INFO - Downloaded 18267715 bytes. 04:10:19 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:10:19 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:10:22 INFO - Return code: 0 04:10:22 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: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 - https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:10:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:10:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:10:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:23 INFO - Downloaded 18267715 bytes. 04:10:23 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:10:23 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:10:27 INFO - Return code: 0 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 - https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:10:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:10:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:10:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:28 INFO - Downloaded 10840194 bytes. 04:10:28 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:10:28 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:10:28 INFO - Return code: 0 04:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:28 INFO - https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:10:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:10:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:10:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CqJctSsQSOqcR72rOi-GrA/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:10:34 INFO - Downloaded 109961887 bytes. 04:10:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:10:34 INFO - mkdir: /builds/slave/test/properties 04:10:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:10:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:10:34 INFO - Contents: 04:10:34 INFO - build_url:https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:10:35 INFO - Running post-action listener: _resource_record_post_action 04:10:35 INFO - Running post-action listener: set_extra_try_arguments 04:10:35 INFO - ##### 04:10:35 INFO - ##### Finished download-and-extract step (success) 04:10:35 INFO - ##### 04:10:35 INFO - ##### 04:10:35 INFO - ##### Running populate-webroot 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: populate_webroot 04:10:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:10:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:10:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:10:35 INFO - '--tppagecycles', 04:10:35 INFO - '7'], 04:10:35 INFO - 'tests': ['tsvgm']}}, 04:10:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:10:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:10:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:10:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:10:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:10:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['damp', 'tps']}, 04:10:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'tests': ['damp', 'tps']}, 04:10:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['dromaeo_dom']}, 04:10:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:10:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['a11yr', 04:10:35 INFO - 'ts_paint', 04:10:35 INFO - 'tpaint', 04:10:35 INFO - 'sessionrestore', 04:10:35 INFO - 'sessionrestore_no_auto_restore', 04:10:35 INFO - 'tabpaint']}, 04:10:35 INFO - 'other-e10s': {'tests': ['a11yr', 04:10:35 INFO - 'ts_paint', 04:10:35 INFO - 'tpaint', 04:10:35 INFO - 'sessionrestore', 04:10:35 INFO - 'sessionrestore_no_auto_restore', 04:10:35 INFO - 'tabpaint']}, 04:10:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:10:35 INFO - 'ts_paint', 04:10:35 INFO - 'tpaint', 04:10:35 INFO - 'sessionrestore', 04:10:35 INFO - 'sessionrestore_no_auto_restore', 04:10:35 INFO - 'tabpaint']}, 04:10:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:10:35 INFO - 'ts_paint', 04:10:35 INFO - 'tpaint', 04:10:35 INFO - 'sessionrestore', 04:10:35 INFO - 'sessionrestore_no_auto_restore', 04:10:35 INFO - 'tabpaint']}, 04:10:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['a11yr', 04:10:35 INFO - 'ts_paint', 04:10:35 INFO - 'tpaint', 04:10:35 INFO - 'sessionrestore', 04:10:35 INFO - 'sessionrestore_no_auto_restore', 04:10:35 INFO - 'tabpaint']}, 04:10:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['a11yr', 04:10:35 INFO - 'ts_paint', 04:10:35 INFO - 'tpaint', 04:10:35 INFO - 'sessionrestore', 04:10:35 INFO - 'sessionrestore_no_auto_restore', 04:10:35 INFO - 'tabpaint']}, 04:10:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['tsvgx', 04:10:35 INFO - 'tsvgr_opacity', 04:10:35 INFO - 'tart', 04:10:35 INFO - 'tscrollx', 04:10:35 INFO - 'cart']}, 04:10:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:10:35 INFO - 'tsvgr_opacity', 04:10:35 INFO - 'tart', 04:10:35 INFO - 'tscrollx', 04:10:35 INFO - 'cart']}, 04:10:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'talos_options': ['--disable-e10s'], 04:10:35 INFO - 'tests': ['tp5o']}, 04:10:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'tests': ['tp5o']}, 04:10:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'talos_options': ['--disable-e10s', 04:10:35 INFO - '--xperf_path', 04:10:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:35 INFO - 'tests': ['tp5n']}, 04:10:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:10:35 INFO - 'talos_options': ['--xperf_path', 04:10:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:10:35 INFO - 'tests': ['tp5n']}}, 04:10:35 INFO - 'talos.zip': {'path': '', 04:10:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:10:35 INFO - Running post-action listener: _resource_record_post_action 04:10:35 INFO - ##### 04:10:35 INFO - ##### Finished populate-webroot step (success) 04:10:35 INFO - ##### 04:10:35 INFO - ##### 04:10:35 INFO - ##### Running create-virtualenv 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: create_virtualenv 04:10:35 INFO - Creating virtualenv /builds/slave/test/build/venv 04:10:35 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:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:10:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:10:35 INFO - Using real prefix '/tools/python27' 04:10:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:10:35 INFO - Installing distribute.............................................................................................................................................................................................done. 04:10:38 INFO - Installing pip.................done. 04:10:38 INFO - Return code: 0 04:10:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:38 INFO - 'HOME': '/Users/cltbld', 04:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:38 INFO - 'LOGNAME': 'cltbld', 04:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:38 INFO - 'MOZ_NO_REMOTE': '1', 04:10:38 INFO - 'NO_EM_RESTART': '1', 04:10:38 INFO - 'PAGER': '/bin/cat', 04:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:38 INFO - 'PWD': '/builds/slave/test', 04:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:38 INFO - 'SHELL': '/bin/bash', 04:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:10:38 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:38 INFO - 'USER': 'cltbld', 04:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:38 INFO - 'XPC_FLAGS': '0x0', 04:10:38 INFO - 'XPC_SERVICE_NAME': '0', 04:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:38 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:38 INFO - Cleaning up... 04:10:38 INFO - Return code: 0 04:10:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:38 INFO - 'HOME': '/Users/cltbld', 04:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:38 INFO - 'LOGNAME': 'cltbld', 04:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:38 INFO - 'MOZ_NO_REMOTE': '1', 04:10:38 INFO - 'NO_EM_RESTART': '1', 04:10:38 INFO - 'PAGER': '/bin/cat', 04:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:38 INFO - 'PWD': '/builds/slave/test', 04:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:38 INFO - 'SHELL': '/bin/bash', 04:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:10:38 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:38 INFO - 'USER': 'cltbld', 04:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:38 INFO - 'XPC_FLAGS': '0x0', 04:10:38 INFO - 'XPC_SERVICE_NAME': '0', 04:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:39 INFO - Downloading/unpacking psutil>=3.1.1 04:10:39 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:39 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:39 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:39 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:39 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:39 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:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:10:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:41 INFO - Installing collected packages: psutil 04:10:41 INFO - Running setup.py install for psutil 04:10:41 INFO - building 'psutil._psutil_osx' extension 04:10:41 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:42 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:42 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:42 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:42 INFO - building 'psutil._psutil_posix' extension 04:10:42 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:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:10:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:10:42 INFO - ^ 04:10:42 INFO - 1 warning generated. 04:10:42 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:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:10:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:10:43 INFO - Successfully installed psutil 04:10:43 INFO - Cleaning up... 04:10:43 INFO - Return code: 0 04:10:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:43 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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:43 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:43 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:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:43 INFO - 'HOME': '/Users/cltbld', 04:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:43 INFO - 'LOGNAME': 'cltbld', 04:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:43 INFO - 'MOZ_NO_REMOTE': '1', 04:10:43 INFO - 'NO_EM_RESTART': '1', 04:10:43 INFO - 'PAGER': '/bin/cat', 04:10:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:43 INFO - 'PWD': '/builds/slave/test', 04:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:43 INFO - 'SHELL': '/bin/bash', 04:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:10:43 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:43 INFO - 'USER': 'cltbld', 04:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:43 INFO - 'XPC_FLAGS': '0x0', 04:10:43 INFO - 'XPC_SERVICE_NAME': '0', 04:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:10:45 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:45 INFO - Installing collected packages: mozsystemmonitor 04:10:45 INFO - Running setup.py install for mozsystemmonitor 04:10:46 INFO - Successfully installed mozsystemmonitor 04:10:46 INFO - Cleaning up... 04:10:46 INFO - Return code: 0 04:10:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:10:46 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:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:46 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:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:46 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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:46 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:46 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:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:10:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:46 INFO - 'HOME': '/Users/cltbld', 04:10:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:46 INFO - 'LOGNAME': 'cltbld', 04:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:46 INFO - 'MOZ_NO_REMOTE': '1', 04:10:46 INFO - 'NO_EM_RESTART': '1', 04:10:46 INFO - 'PAGER': '/bin/cat', 04:10:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:46 INFO - 'PWD': '/builds/slave/test', 04:10:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:46 INFO - 'SHELL': '/bin/bash', 04:10:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:10:46 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:10:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:46 INFO - 'USER': 'cltbld', 04:10:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:46 INFO - 'XPC_FLAGS': '0x0', 04:10:46 INFO - 'XPC_SERVICE_NAME': '0', 04:10:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:10:46 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:46 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:46 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:46 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:46 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:46 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:48 INFO - Downloading blobuploader-1.2.4.tar.gz 04:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:10:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:10:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:49 INFO - Downloading docopt-0.6.1.tar.gz 04:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:10:50 INFO - Installing collected packages: blobuploader, requests, docopt 04:10:50 INFO - Running setup.py install for blobuploader 04:10:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:10:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:10:50 INFO - Running setup.py install for requests 04:10:50 INFO - Running setup.py install for docopt 04:10:50 INFO - Successfully installed blobuploader requests docopt 04:10:50 INFO - Cleaning up... 04:10:50 INFO - Return code: 0 04:10:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:50 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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:50 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:50 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:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:50 INFO - 'HOME': '/Users/cltbld', 04:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:50 INFO - 'LOGNAME': 'cltbld', 04:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:50 INFO - 'MOZ_NO_REMOTE': '1', 04:10:50 INFO - 'NO_EM_RESTART': '1', 04:10:50 INFO - 'PAGER': '/bin/cat', 04:10:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:50 INFO - 'PWD': '/builds/slave/test', 04:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:50 INFO - 'SHELL': '/bin/bash', 04:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:10:50 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:50 INFO - 'USER': 'cltbld', 04:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:50 INFO - 'XPC_FLAGS': '0x0', 04:10:50 INFO - 'XPC_SERVICE_NAME': '0', 04:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Lup8_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-c2sZXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-CLu23w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2gNKNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ktWy03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9nCEtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-BmHylS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Cl1l9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-6V3OA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-mfrF2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-FSnBeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-CLt82n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-aLBh8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-8qE10U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-QOxcaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-LRoh4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-MRfflS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:10:52 INFO - Running setup.py install for manifestparser 04:10:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:10:53 INFO - Running setup.py install for mozcrash 04:10:53 INFO - Running setup.py install for mozdebug 04:10:53 INFO - Running setup.py install for mozdevice 04:10:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:10:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:10:53 INFO - Running setup.py install for mozfile 04:10:53 INFO - Running setup.py install for mozhttpd 04:10:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:10:53 INFO - Running setup.py install for mozinfo 04:10:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:10:53 INFO - Running setup.py install for mozInstall 04:10:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Running setup.py install for mozleak 04:10:54 INFO - Running setup.py install for mozlog 04:10:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Running setup.py install for moznetwork 04:10:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Running setup.py install for mozprocess 04:10:54 INFO - Running setup.py install for mozprofile 04:10:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:10:54 INFO - Running setup.py install for mozrunner 04:10:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:10:55 INFO - Running setup.py install for mozscreenshot 04:10:55 INFO - Running setup.py install for moztest 04:10:55 INFO - Running setup.py install for mozversion 04:10:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:10:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:10:55 INFO - Cleaning up... 04:10:55 INFO - Return code: 0 04:10:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:10:55 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:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:55 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:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:10:55 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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:55 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:55 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:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:10:55 INFO - 'HOME': '/Users/cltbld', 04:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:10:55 INFO - 'LOGNAME': 'cltbld', 04:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:10:55 INFO - 'MOZ_NO_REMOTE': '1', 04:10:55 INFO - 'NO_EM_RESTART': '1', 04:10:55 INFO - 'PAGER': '/bin/cat', 04:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:55 INFO - 'PWD': '/builds/slave/test', 04:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:10:55 INFO - 'SHELL': '/bin/bash', 04:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:10:55 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:10:55 INFO - 'USER': 'cltbld', 04:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:10:55 INFO - 'XPC_FLAGS': '0x0', 04:10:55 INFO - 'XPC_SERVICE_NAME': '0', 04:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:10:55 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-xYi_3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:10:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:10:55 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-w3brFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:10:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:10:55 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WOBqky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-cPxgVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-KTp4XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Hk8tZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9D4R_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-frbpXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-LI47h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-DGnjuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-w1UHKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:10:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:10:56 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-MKCCfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:10:57 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:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:10:57 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-auHaep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:10:57 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:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:10:57 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-wFJqoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:10:57 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:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:57 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Iamn8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:10:57 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:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:10:57 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WhgYGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:10:57 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:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:10:57 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-hCdO8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:10:57 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:57 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:57 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:57 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:57 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:57 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:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:10:57 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:57 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:57 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:57 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:57 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:57 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:11:00 INFO - Downloading blessings-1.6.tar.gz 04:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:11:00 INFO - Installing collected packages: blessings 04:11:00 INFO - Running setup.py install for blessings 04:11:00 INFO - Successfully installed blessings 04:11:00 INFO - Cleaning up... 04:11:00 INFO - Return code: 0 04:11:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:11:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:00 INFO - Reading from file tmpfile_stdout 04:11:00 INFO - Current package versions: 04:11:00 INFO - blessings == 1.6 04:11:00 INFO - blobuploader == 1.2.4 04:11:00 INFO - docopt == 0.6.1 04:11:00 INFO - manifestparser == 1.1 04:11:00 INFO - mozInstall == 1.12 04:11:00 INFO - mozcrash == 0.17 04:11:00 INFO - mozdebug == 0.1 04:11:00 INFO - mozdevice == 0.48 04:11:00 INFO - mozfile == 1.2 04:11:00 INFO - mozhttpd == 0.7 04:11:00 INFO - mozinfo == 0.9 04:11:00 INFO - mozleak == 0.1 04:11:00 INFO - mozlog == 3.2 04:11:00 INFO - moznetwork == 0.27 04:11:00 INFO - mozprocess == 0.22 04:11:00 INFO - mozprofile == 0.28 04:11:00 INFO - mozrunner == 6.11 04:11:00 INFO - mozscreenshot == 0.1 04:11:00 INFO - mozsystemmonitor == 0.3 04:11:00 INFO - moztest == 0.7 04:11:00 INFO - mozversion == 1.4 04:11:00 INFO - psutil == 3.1.1 04:11:00 INFO - requests == 1.2.3 04:11:00 INFO - wsgiref == 0.1.2 04:11:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:00 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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:11:00 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:11:00 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:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:00 INFO - 'HOME': '/Users/cltbld', 04:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:00 INFO - 'LOGNAME': 'cltbld', 04:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:00 INFO - 'MOZ_NO_REMOTE': '1', 04:11:00 INFO - 'NO_EM_RESTART': '1', 04:11:00 INFO - 'PAGER': '/bin/cat', 04:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:00 INFO - 'PWD': '/builds/slave/test', 04:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:00 INFO - 'SHELL': '/bin/bash', 04:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:11:00 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:00 INFO - 'USER': 'cltbld', 04:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:00 INFO - 'XPC_FLAGS': '0x0', 04:11:00 INFO - 'XPC_SERVICE_NAME': '0', 04:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 INFO - Cleaning up... 04:11:00 INFO - Return code: 0 04:11:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11:00 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 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:11:00 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:11:00 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:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:00 INFO - 'HOME': '/Users/cltbld', 04:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:00 INFO - 'LOGNAME': 'cltbld', 04:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:00 INFO - 'MOZ_NO_REMOTE': '1', 04:11:00 INFO - 'NO_EM_RESTART': '1', 04:11:00 INFO - 'PAGER': '/bin/cat', 04:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:00 INFO - 'PWD': '/builds/slave/test', 04:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:00 INFO - 'SHELL': '/bin/bash', 04:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:11:00 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:00 INFO - 'USER': 'cltbld', 04:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:00 INFO - 'XPC_FLAGS': '0x0', 04:11:00 INFO - 'XPC_SERVICE_NAME': '0', 04:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:01 INFO - Downloading/unpacking jsonschema 04:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:11:03 INFO - Downloading/unpacking functools32 (from jsonschema) 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:04 INFO - Downloading functools32-3.2.3-2.tar.gz 04:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:11:04 INFO - warning: no files found matching '*.txt' 04:11:04 INFO - no previously-included directories found matching 'build' 04:11:04 INFO - no previously-included directories found matching 'dist' 04:11:04 INFO - no previously-included directories found matching '.git*' 04:11:04 INFO - Installing collected packages: jsonschema, functools32 04:11:04 INFO - Running setup.py install for functools32 04:11:04 INFO - warning: no files found matching '*.txt' 04:11:04 INFO - no previously-included directories found matching 'build' 04:11:04 INFO - no previously-included directories found matching 'dist' 04:11:04 INFO - no previously-included directories found matching '.git*' 04:11:04 INFO - Successfully installed jsonschema functools32 04:11:04 INFO - Cleaning up... 04:11:04 INFO - Return code: 0 04:11:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:11: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:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11: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:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:11: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107254a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10725a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae19d7b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, '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.wpGx8GQsrP/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.2MghzUNj2T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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:11: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', 'flake8'] in /builds/slave/test/build 04:11: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 flake8 04:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:04 INFO - 'HOME': '/Users/cltbld', 04:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:04 INFO - 'LOGNAME': 'cltbld', 04:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:04 INFO - 'MOZ_NO_REMOTE': '1', 04:11:04 INFO - 'NO_EM_RESTART': '1', 04:11:04 INFO - 'PAGER': '/bin/cat', 04:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:04 INFO - 'PWD': '/builds/slave/test', 04:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:04 INFO - 'SHELL': '/bin/bash', 04:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:11:04 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:04 INFO - 'USER': 'cltbld', 04:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:04 INFO - 'XPC_FLAGS': '0x0', 04:11:04 INFO - 'XPC_SERVICE_NAME': '0', 04:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:04 INFO - Downloading/unpacking flake8 04:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:11:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:11:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:11:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:11:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:11:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:11:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:11:08 INFO - no previously-included directories found matching 'docs/_build' 04:11:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:11:08 INFO - Running setup.py install for pep8 04:11:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:11:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:11:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:11:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:11:08 INFO - no previously-included directories found matching 'docs/_build' 04:11:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:11:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:11:08 INFO - Cleaning up... 04:11:08 INFO - Return code: 0 04:11:08 INFO - Running post-action listener: _resource_record_post_action 04:11:08 INFO - Running post-action listener: _start_resource_monitoring 04:11:08 INFO - Starting resource monitoring. 04:11:08 INFO - ##### 04:11:08 INFO - ##### Finished create-virtualenv step (success) 04:11:08 INFO - ##### 04:11:08 INFO - ##### 04:11:08 INFO - ##### Running install step. 04:11:08 INFO - ##### 04:11:08 INFO - Running pre-action listener: _resource_record_pre_action 04:11:08 INFO - Running main action method: install 04:11:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:09 INFO - Reading from file tmpfile_stdout 04:11:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:11:09 INFO - Reading from file tmpfile_stdout 04:11:09 INFO - Output received: 04:11:09 INFO - Usage: mozinstall [options] installer 04:11:09 INFO - Options: 04:11:09 INFO - -h, --help show this help message and exit 04:11:09 INFO - -d DEST, --destination=DEST 04:11:09 INFO - Directory to install application into. [default: 04:11:09 INFO - "/builds/slave/test"] 04:11:09 INFO - --app=APP Application being installed. [default: firefox] 04:11:09 INFO - mkdir: /builds/slave/test/build/application 04:11:09 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:11:09 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:42 INFO - Reading from file tmpfile_stdout 04:11:42 INFO - Output received: 04:11:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:11:42 INFO - Running post-action listener: _resource_record_post_action 04:11:42 INFO - ##### 04:11:42 INFO - ##### Finished install step (success) 04:11:42 INFO - ##### 04:11:42 INFO - ##### 04:11:42 INFO - ##### Running run-tests step. 04:11:42 INFO - ##### 04:11:42 INFO - Running pre-action listener: _resource_record_pre_action 04:11:42 INFO - Running main action method: run_tests 04:11:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:11:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:11:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:11:42 INFO - 2016-05-17 04:11:42.660 screenresolution[1643:7678] starting screenresolution argv=screenresolution get 04:11:42 INFO - 2016-05-17 04:11:42.662 screenresolution[1643:7678] Display 0: 1600x1200x32@60 04:11:42 INFO - 2016-05-17 04:11:42.669 screenresolution[1644:7680] starting screenresolution argv=screenresolution list 04:11:42 INFO - Available Modes on Display 0 04:11:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:11:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:11:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:11:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:11:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:11:42 INFO - Intel Iris: 04:11:42 INFO - Chipset Model: Intel Iris 04:11:42 INFO - Type: GPU 04:11:42 INFO - Bus: Built-In 04:11:42 INFO - VRAM (Dynamic, Max): 1536 MB 04:11:42 INFO - Vendor: Intel (0x8086) 04:11:42 INFO - Device ID: 0x0a2e 04:11:42 INFO - Revision ID: 0x0009 04:11:42 INFO - Displays: 04:11:42 INFO - Display: 04:11:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:11:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:11:42 INFO - Display Serial Number: 1600x1200 60 04:11:42 INFO - Main Display: Yes 04:11:42 INFO - Mirror: Off 04:11:42 INFO - Online: Yes 04:11:42 INFO - Rotation: Supported 04:11:42 INFO - Return code: 0 04:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:11:42 INFO - Python 2.7.3 04:11:42 INFO - Return code: 0 04:11:42 INFO - grabbing minidump binary from tooltool 04:11: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:11:42 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 0x1070f8d30>, '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 0x106f1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070efb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:11:42 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:42 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:42 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:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:11:43 INFO - Return code: 0 04:11:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:11:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:11:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:11:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:11:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:11:44 INFO - Return code: 0 04:11:44 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/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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:44 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/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wpGx8GQsrP/Render', 04:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:11:44 INFO - 'HOME': '/Users/cltbld', 04:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:11:44 INFO - 'LOGNAME': 'cltbld', 04:11:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:11:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:11:44 INFO - 'MOZ_NO_REMOTE': '1', 04:11:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:11:44 INFO - 'NO_EM_RESTART': '1', 04:11:44 INFO - 'PAGER': '/bin/cat', 04:11:44 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:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:44 INFO - 'PWD': '/builds/slave/test', 04:11:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:11:44 INFO - 'SHELL': '/bin/bash', 04:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2MghzUNj2T/Listeners', 04:11:44 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:11:44 INFO - 'USER': 'cltbld', 04:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:11:44 INFO - 'XPC_FLAGS': '0x0', 04:11:44 INFO - 'XPC_SERVICE_NAME': '0', 04:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:11:44 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/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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:44 INFO - mozversion application_buildid: 20160517021529 04:11:44 INFO - mozversion application_changeset: 92b07e6e84bf828040ac5231eafea31c843e0472 04:11:44 INFO - mozversion application_display_name: Nightly 04:11:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:11:44 INFO - mozversion application_name: Firefox 04:11:44 INFO - mozversion application_remotingname: firefox 04:11:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:11:44 INFO - mozversion application_vendor: Mozilla 04:11:44 INFO - mozversion application_version: 49.0a1 04:11:44 INFO - mozversion platform_buildid: 20160517021529 04:11:44 INFO - mozversion platform_changeset: 92b07e6e84bf828040ac5231eafea31c843e0472 04:11:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:11:44 INFO - mozversion platform_version: 49.0a1 04:11:44 INFO - using testdate: 1463483504 04:11:44 INFO - actual date: 1463483504 04:11:44 INFO - starting webserver on 'localhost:49275' 04:11:44 INFO - SUITE-START | Running 2 tests 04:11:44 INFO - TEST-START | dromaeo_css 04:11:44 INFO - operating with platform_type : mac_ 04:11:44 INFO - Initialising browser for dromaeo_css test... 04:11:44 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpupJuFL/profile http://localhost:49275/getInfo.html) 04:11:46 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:11:46 INFO - PROCESS | 1662 | colorDepth:24 04:11:46 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:11:46 INFO - PROCESS | 1662 | __metrics 04:11:46 INFO - PROCESS | 1662 | 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:46 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:11:47 INFO - TEST-INFO | 1662: exit 0 04:11:47 INFO - Browser initialized. 04:11:47 INFO - Running cycle 1/1 for dromaeo_css test... 04:11:47 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpupJuFL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:11:59 INFO - PROCESS | 1664 | RSS: Main: 239206400 04:11:59 INFO - PROCESS | 1664 | 04:12:50 INFO - PROCESS | 1664 | 1463483570298 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:15:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 04:15:03 INFO - PROCESS | 1664 | RSS: Main: 426545152 04:15:03 INFO - PROCESS | 1664 | 04:18:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 04:18:07 INFO - PROCESS | 1664 | RSS: Main: 377720832 04:18:07 INFO - PROCESS | 1664 | 04:21:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 04:21:10 INFO - PROCESS | 1664 | RSS: Main: 371691520 04:21:10 INFO - PROCESS | 1664 | 04:24:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 04:24:13 INFO - PROCESS | 1664 | RSS: Main: 372023296 04:24:13 INFO - PROCESS | 1664 | 04:27:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 04:27:17 INFO - PROCESS | 1664 | RSS: Main: 343093248 04:27:17 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 04:30:20 INFO - PROCESS | 1664 | RSS: Main: 385511424 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | __start_tp_report 04:30:20 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 04:30:20 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 04:30:20 INFO - PROCESS | 1664 | |i|pagename|runs| 04:30:20 INFO - PROCESS | 1664 | |0;dojo.html;3421.4696722008084;3454.965450345496;3482.355764183628;3492.1793378556035;3521.7886926784395;5642.15367694846;5654.491095801377;5680.573956953224;5727.112297593874;5761.971190144049;5706.087026075828;5772.0187568113415;5775;5779.62432441891;5786.132080187974;5154.871128221796;5190.377154741417;5218.791248350061;5232.372115346164;5275.525202731754;5185.222216667492;5195.298634684307;5203.9219411708855;5204.297419848329;5207.453217412174;5180.067587834188;5186.014657215117;5193.16909294513;5196.948030519703;5208.192730126818;5288.709120998344;5295.099833028395;5310.20346947957;5314;5353.143497040455;5146.125158722989;5148.150555158394;5152.458991805881;5220.947790522084;5238.92141617876;5590.18942253375;5601.215829783833;5654.180143879114;5661.716914154317;5684.687342196177;6311.684415779238;6317.210348706411;6322.968385158045;6330.208723909512;6337.651429171394;6142.815715528542;6260.373962603749;6334.493240540792;6341.587796793194;6345.33373995733;5881.4706676399755;5889.793857214977;5898.203742494751;5915.497182739429;5933.792317268875;6332.398422149889;6333.68331583419;6340.239171299474;6343.714532846033;6348.746050158034;6080.787172448943;6087.281609052182;6118.862843290023;6198.411150940653;6247.406496382836;6322.71547780351;6328.303886572473;6340.1440805490665;6340.936590634127;6356.173697419306;6210.347913469111;6272.717727702265;6291.213598300213;6297.4647154992335;6320.936790632127;6108.493847774667;6155.538334624832;6170.691465426711;6215.12988181655;6277.780277690259;4944.283078953532;4969.130402179604;4979.153543897546;4986.102501549683;4995.500449954975;4453.643708503312;4492.595666390041;4495.887602809956;4514.841980530666;4552.590266875997;5741.62679425836;5755.712214389264;5773.336066352424;5785.161151633074;5825.563082768809;6234.781782637677;6272.874542509125;6290.339514350927;6297.2128483939505;6376.075469057054;5596.1605759136455;5663.235463212413;5684.431556844365;5703.486686198262;5748.965186266512;4564.2240819060835;5363.758630861621;5365.463453654681;5378;5390.110631745796;5508.256385388001;6227.408396202353;6347.968260158762;6388.744450221939;6398.680065996775;6014.3159915223805;6217;6231.501479881689;6269.686515674107;6280.27776805655;5692.402297758676;6303.684815759286;6334.018227174795;6360.968195158995;6367.872642547216;6138.631682099089;6197.132401463708;6200.906986395298;6211.689415529115;6212;5051.292819005415;5722.998475266729;6247;6267.1539342188225;6330.620162790247;6153.692315384303;6174.104754810617;6178.783742568989;6182.536309776695;6191.783287584913;5160.767765450489;6135.53983451234;6141.508679305734;6153.907691384722;6167.876642467215;5782.768689252551;5858.794942177174;5918.940810591839;5934.643921364732;5942.346341902474;6075.2405949256345;6085.178500902499;6101.176341194059;6105.572609917441;6112.358202388685;5900.557458190567;5932.79235226765;5936.970315148396;5943.959807033665;5950.5239580834295 04:30:20 INFO - PROCESS | 1664 | |1;ext.html;486.45031114840225;507.14545990006866;511.91474074961087;516.2926790297292;522.9529342359187;30003.399932001346;30214;42886.57113428865;43116.56883431165;45117.09765804682;44276.89307767308;44717;44736.55263447373;44832.10335793282;45001.324980125246;8606.536888728931;8750.256228220609;9630.614775408993;9698.224142068617;9860.901390986106;8611;11506.194566380356;11700.824487632692;11726.648200553997;11850.229735067236;11667.24162929411;11703.648890533255;11760.353180575064;11794.7641047179;11801.409929503534;8631.568421578895;11560.421978901106;11749.941250293736;11794.410279485992;11854.881451185507;9978.650747223812;11710.94144529272;11811.527538898434;11841.40792960357;11852.992495637882;39812;40067.7996610018;40152.598474015176;40153.598464015275;40197.59802401997;3230.2247460609283;3311.586051743532;3337.332533493311;3532.6643968822923;3682.9447558286647;28378.57432138499;28968.565471517944;29238.56142157869;29635;29688.109356719335;2715.7507546528623;2728.7953403494816;2770.3074231442138;2809.5083360411845;2857.7142285771465;530180;530316;530731.9999999962;531373;532292;35687.821560892546;60573.39426605678;60972.69513652492;60997.39002609917;61131.38868611346;36640.45039324412;37790.62209377871;37834.43248351277;37838;37926.43110353347;12857.485700571873;15287.770683439758;15350.002249853724;15351.61620959485;15380;11630.6510804676;11872;12740.044496662787;12861.485540578458;12927.5475358362;8240.21717936795;8251.422400431911;8252.587370631445;8313.833723325499;8321.95839020801;2995.2961054152256;3345.9498107528407;3459.4810778383444;3462.4806279058344;3477.7217822574135;984.7833476635129;986.1913231150388;992.8957459466797;1072.3940973350188;1082.868402519369;27937;29807.254818629273;30343.393132137677;30503.779848806244;30608.54087188694;1135.2847705945367;1198.3888217009214;1221.2367270455966;1221.5724496426358;1252.9248245105323;14816.92591537057;15917.681646367007;15932.044077355396;16024.519264422086;16094.275757590936;657140;662066;665187;666917;667362;62693;63223.683881581215;63230;63262.73474530576;63280.36719632837;1324.7615429222833;1473.0940471609667;1494.3424893047024;1519.931603077842;1531.4104069932932;29755;29880.70119298779;29927.401451971273;30116.831683168315;30247.158471029892;51009.744951275;51099;51823.48176518337;52018.47981520136;52168.73915630549;585785;595250;597155;599109;599942;23116.653250200925;25521;26179;26344.60483092717;27112.864435678486;37318.440223397214;43929;45409.864753380105;45430.77284613556;46120.769396152806;34567.4814877772;35213.64786352206;36833.44749828701;37643;37748.43377349287;36042.63957360393;36349.27301454009;36388.81805590955;37158.8142059288;37166.88499345024;36577.45133822942;37018.25963480769;37161.25677486381;37542.2491550162;37921.05197370088;30444.086677399715;32617.836910815295;32716.83641581777;32772.34455310833;32785.672143278265;19908.90045549763;22258.22096226624;23986.640200396658;24502.387440314134;24592.142002393583 04:30:20 INFO - PROCESS | 1664 | |2;jquery.html;371.3260432315348;393.2430072111186;396.259312093834;397.14811728841653;415.67577289714035;20125.396238112862;20240.89879550604;21176.682349764767;21183.258585949494;21215.045322960465;20187.495312617193;20747.481312967182;20831.895840520814;21329.93350332485;21384;5290.153575427932;5650.067738823089;5657.915131273035;5688.687122208277;5695.316562012565;5516.3380394352735;5610.214569960209;5615.578831587647;5620.522255608258;5688.488036076752;6034.728437220336;6041.214642096521;6061.515078793708;6072.08918662202;6087.391260873921;5432.619716619838;5778.5377169826315;6198.380161983833;6673;6674.3325667433355;5688.63023903445;5836.503897168745;5862.179294898718;5874.295084589834;5875.588708790387;13613.795793063113;13639;14043.578692639237;14045.578632641038;14058.226797526131;1478.9926050369786;2165.0798410675557;2199.82401407887;2289.8740569268684;2307.3428615644225;9708.951455242679;9741.512924353825;9922;10295.639652612197;10456.215783816167;1532.256855425118;1569.199708148841;1710.5894585299563;1747.8339557741995;1782.2514543891466;109149;120409.98975025695;127007;134471.6552834469;134729;11695.824562631567;11741.060715142768;11768.52925882955;11791.233569817934;11997.76004479923;15273.465428710164;15369.615759606098;15370.692586148438;15374.385024599114;15410.460633877761;7305.817354566072;7711.149324162161;8459.027211870714;8770.736877893672;9002.68490602839;8789.340799440068;8797.340199485065;8805.295576353876;8917.821643567093;8934.374593778502;4614.861554153381;4700.26090346574;4711.481737008926;4714;4770.427548694179;1672.1355688407582;1710.5638062294283;1932.4009557037364;1956.9823691680247;1967.3409407848244;924.3945215883607;946.5929650250321;979.9412035277763;1000.429755039635;1013.614826365991;21232.36302910915;22518.549629007328;22620.773792262196;22774.65838012431;22956.54086918252;948.606328373731;964.5852283518154;989.8416253399565;1016.7305663999047;1039.2205845615788;8118.188181181953;9442.583612458186;10898.346099234072;11148.665540033811;11283.54865805375;258891;266722;492708;621876;628572;133709;141584;144956.55043449643;148981.08151347865;149475;1049.41757324684;1064.324154162097;1066.1364294921138;1085.1102096280974;1086.266769930294;14928.402863885323;18981.620367592295;19005.524861878563;19008.904955475686;19045.809541904957;39354;55024.17463737967;56156.5960850982;56901.30141540786;57048;80408.19591804007;81629.18370816454;81948.59025704833;82589;82903.7564436546;7156.463265255023;7570.659320330488;7627.771166865003;7844;8418.663253470038;11690.70773230676;12857.292848893401;12979.351032448529;13372.93313533426;13429.194248345131;11191.99272065518;11270.830937536108;11488.770224595628;11516.94241528787;11612.071034317403;10668.519916603616;10732.785344293226;10888.727781805519;11018.39398833072;11365.31808091517;9810.75473113178;10006.09945104944;11421.086313095098;11544.59593914209;11798.823017654571;6533.67331633426;7095.361417472453;7388.741394051182;7465.402767778456;7984.24149705777;8365.456245344034;11050.557977680803;11257.71855703614;11265.662030139109;11612.245204061708 04:30:20 INFO - PROCESS | 1664 | |3;mootools.html;1429.4496618801759;1621.3352525464559;1652.1739130434783;1887.820657037582;2016.395081475557;8074.838503229932;8664.913350866489;8870.512121833297;8873.73378798637;8992.775180620487;8604.483730976146;8843;8847.646094156242;8852.981907080688;8856.202941735259;2137.6045431595126;2225.532638145983;2366.692329997098;2386.809055275578;2419.2016634510655;2259.3334966185016;2456.9017239310406;2498.950020999579;2499.6125600531946;2500.1374525788574;2269.546090781842;2273.068042102738;2290.874001929893;2413.9758602414013;2471.1104002559064;2117.17430202222;2341.840788809533;2402.3994001499627;2421.128393778238;2430.2952143878256;2430.1251549442186;2439.2682195341326;2463.790577800889;2473.1591258972035;2473.1591258972035;9154.038825923315;9214.723558293263;9232.907670923272;9233.39982901116;9279.907200927972;5470.890582188374;5628;5645.633033852787;5654.151877218409;5655.943440565624;3601.58581763098;3652.1782598915297;3753.0429740416293;3759.3233218020746;3772;988.8566157907063;1004.6182450668699;1011.2415688233824;1011.3072545306488;1013.6857574152036;12390.132690711655;12392.56626018085;12396.380180991004;12399.318037507934;12401.379931003414;4153.854615088458;4835.95164048362;4883.145449546315;4982.476839931827;5001.999600079998;9210.723678289662;9872.901270987199;10270.229732770073;10289.485525723683;10295.279330446796;5487.75305111271;5546.500814926676;5553.555715542747;5563.193336966117;5564.081926482085;4131.9173616528105;4515.458145022619;4674.509176536482;5022.020705962375;5025.698458092526;4035.6966749494654;4059.573744756758;4118.402831589463;4169.020280234143;4174.519930208005;1239.3369547292154;1247.1332423965257;1258.3205069262679;1265;1294.70869054462;780.8399278147966;838.3544670603601;878.2774684245469;928.9721308360624;971.5482300730195;4998.6750861193905;5148.588112950955;5817.592768506249;5958.702064896738;6164.075388691643;968.8207681578931;1015.9898401016043;1018.4042335233823;1019.2864994503877;1070.5878236878857;3679.5952445231005;3893.6690381317903;3904.375299951994;3957.9802100989887;4001.1797581495725;10965;11009.889901101047;11405.372704501166;11419.143564232716;11432.199746017699;9814.65648702292;11207.159463040141;11332.830007549894;11348.886511134948;11367.829482557769;8432.114627964098;8632.568371581396;8634.179752923588;8635.963684358043;8658.87011694825;4711.764411779466;5889.734961926809;5933.2880054393745;6082.239720034996;6179.351168127282;10476.790464190826;10623.521941512496;10649.680509584725;10655.454959030742;10662.573497060032;9297.814043718952;9643.9035609643;9749.268804839523;10372.636957706443;10384.688459346231;7058.223595404399;7300.306470885257;7346.6326683666675;7698.230176982257;7902.407319451179;8487.490750554987;8619.569021549023;8701.608427620646;8707.434016789142;8709.38876307885;7539.061914446047;7728.536287822749;7784.9610751945875;7892.723754668559;7906.288434040964;7536.660850261642;7613.505122167041;7737.6518056686455;7779.494332868346;7789.33790627803;7175.784726458214;7251.927480725336;7267.091613548307;7269.055022846996;7276.927230727625;4076.571959944163;4800.135975524439;4897.8285759998225;5070.062038522885;5124.948750512447;5007.048660754445;5060.949390506195;5092.898142037065;5169.508896654812;5277.393099793419 04:30:20 INFO - PROCESS | 1664 | |4;prototype.html;308.3293835906902;318.15212458797305;322.38102842542315;325.1951420234919;329.18855022369854;4961.950380496194;5148;5418.701971391577;5445.945540544593;5517.475839795218;4891.926621100686;5090.770915308811;5377.274068000819;5395.352557693082;5452.754626041828;3525.294941011795;3724.5902950675404;3945.191235796655;3958.3270843956598;4029.9798501007454;3646.3618866698353;4039.676825853939;4075.286824805662;4094.0992981544014;4112.938305925413;3560.590532088817;3841.9807900960595;4019.638232559069;4092.85675001375;4130.690198235129;3433.1931995980926;3760.7179461540495;3801.74542401007;3878.1495600733297;4118.279301122307;3541.8937431877084;3577.6422357764277;3578.6738619897014;3579.158897659048;3582.6775590197008;4734.739589322586;5079.644424890259;5218.608604354688;5221.347331583552;5239.768654366221;4187.706860519765;4374.781260936972;4408.7354758714255;4661.953380466186;4664.113818374498;5089.949100508985;5488.4785945335125;5633.57748168889;5651.32184137902;5690.841582921477;3377.6791204835745;3966.6513385449084;3968.7023473239624;3969.325214713506;3984.4620976168135;4677.906441871178;5789.8263052108505;5842.649441033509;5862.736176872051;5975.731092100866;3964.920701585952;3967.274235707419;4009.115715613657;4009.599040095967;4020.798960052044;4049.635532802003;4072.857449989295;4081.591840815954;4086.6117718816663;4148.1792381181085;3956.228535435562;4719.905601887943;4720.763961801897;4722.645801564828;4732.320026390651;5214.7914083436235;5235.685858848481;5490.121580547173;5724.542036637059;5740.253767010262;1163.2148299897704;1379.6275005748391;1403.5368328451586;1410.7883817427469;1413.922234277104;860.7245681381897;871.0984131424073;875.1205038935828;900.9683911920945;920.742192186189;4549.5905368517;4784.952150478521;5085.796568137233;5212.192110222922;5348.679079255257;5082.695038297714;5310.7610157543;5340.225667278223;5382.30030096085;5542.8337149885565;4671.11248862715;5061.4179369373;5104.948950510522;5387.730613469311;5415.918761218585;3058.1742929408933;3060.1890499017777;3080.414721203009;3082.460569400346;3094.272845881216;5442.9930462864495;5910.463279547263;6030.969845150834;6090.817275481742;6155.753769849245;5347.545458635982;5554.666719996813;5696.3449203341315;6081.969590152109;6113.84715382119;5593.300837395325;6071.148299768613;6108.5723999319625;6139.47814435769;6150.753969841246;5145.507802737314;5209.609279303992;5908.586398952032;5915.674637894957;5940.46535811779;4815.879603009953;5349.144136938071;5804.158397032491;5825.271841019872;5863.736131874161;5523.342997100934;5826.42686474658;6050.09595394222;6133.877322453615;6135.570510064431;2969.658489273761;3268.362669279468;3485.4946032825605;4087.836486540505;4135.731177473454;5558.249636299048;5733.0540460823495;5799.4780469757925;5828.825135245949;5882.617629854046;4643.099796171233;4872.4153101628035;5392.840539284073;5692.117721753135;5726;4646.488886222581;5384.784608615768;5610.943890561042;5704.828855134353;5710.2576665033275;4444.911101778011;4542.477615074176;4635.65232607549;5480.506754392124;5502.945865044939;5349.144136938226;5407.513323800877;5629.155626656035;5635.126555383922;5636.408177141505;5646.096624540054;5647.124695672177;5647.199880101881;5661.46216109469;5669.3480249770155 04:30:20 INFO - PROCESS | 1664 | |5;yui.html;640.3564417760152;681.9147606549179;727.5416487612803;728.9963550182248;757.3146302596152;14961.326740296685;15175.544733657995;15289;15393.538193854203;15420.845791542095;14145.504907328246;14182.645433864158;14183.361748721307;14193.645158871033;14205.360758765855;707.4305184326615;731.0605871455181;904.6200595749768;916.5005072235614;937.5359197197397;904.2268860124598;924.5654542365111;924.7410724997012;924.7456949338952;925.842606756853;974.8878878928907;976.6825781620967;977.0376179463259;977.4184360305642;978.872746542952;742.3319012888389;763.2939530933864;765.5368502056288;767.5586537740766;970.7330484116853;710.232948415715;732.2274999875129;732.3847967707098;733.9082614673166;735.1509007096812;14555.563333099917;14605.342759575844;14612.561623151216;14616.926915365462;14657.70684586313;12125.878741212651;12192;12469.52072590638;12470.314132722697;12532.874671253261;10699.19756018294;10860.3483790972;10861.1854110942;10899.509522071527;11273.37996410197;3541.415666415064;3790.7915064671433;3957.742746721454;4084.7549147051272;4101.569335219819;80832.19167808305;81961.59019204926;82475.17524824735;83259.58370208171;83803.16196838014;9407.106324899123;9613;9624.66313679018;9778.511074446249;9887.950560247153;21134.47163820917;21142.942852857297;21149.259775908078;21158.576828463345;21179;6044.727987240584;7170.92829071713;7210.711571537185;7237.493375463667;7402.592857392788;3954.070793363557;4009.51885773709;4041.4341992121126;4062.0657248832936;4093.2222877653153;3364.8149351785396;3457.9827100864836;3464.8787292444645;3465.081753335368;3468.687818096333;640.1038546035494;645.8127143128534;655.1875674164097;655.885220086483;656.1371796088129;461.2343509773759;506.994648389822;551.9448055194448;563.2199403825649;575.8761866198787;6798.286179951133;7612.657430415645;7706.460547761715;7903.249191326815;8130;535.3228166369546;544.248936467672;550.7796881247453;566.6005466146439;579.2035950567969;3716.2939041582554;3760.9435858462143;3789.317922773927;3873.9418908716393;3984.6812255019527;81723.7741433879;93099.5345023284;93222.06777932134;93576;96356.51821740846;83615.58192209122;83783.16216837753;84222.57888710518;84355;84701.57649211715;74361.25638743652;74604.62697686476;74651.62674186703;75364.24635753682;76518;7262.527935684164;7292.307230813064;7476.102867655699;7525.8118547036765;7753.457257991887;28737.42525149527;29446.85276573689;39303.80348098356;46758.76620616875;48056.759716201195;59903.70048149731;63610.68194658997;64116;64867;65443.67278163579;9837.70486885366;9851;9867.40795552269;9874.61438949155;10093.293469457067;10186.694399168036;10623.575056997943;10769.730756731144;10858.837117443087;11357.716057098638;7888.967247640869;9785.412875227508;10220.02909723575;10224.64213752515;10283.640072597722;11051.115910727283;11126.888731112585;11318.660440186808;11343.886561134283;11826.82259766087;10901.83647245276;11125.109991200523;11362.659120226406;11412.48643811047;12216.14486985938;9446.622135114518;9464.1009104135;9488.478133702713;9677.419354838732;9894.257930655085;9431.339806213498;9602.66390676351;9614.807703846023;9663.371880827724;9668.419894806053 04:30:20 INFO - PROCESS | 1664 | __end_tp_report 04:30:20 INFO - PROCESS | 1664 | __start_cc_report 04:30:20 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1881 04:30:20 INFO - PROCESS | 1664 | __end_cc_report 04:30:20 INFO - PROCESS | 1664 | __startTimestamp1463484620802__endTimestamp 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | ------- Summary: start ------- 04:30:20 INFO - PROCESS | 1664 | Number of tests: 6 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5747.23 Median:5918.94 stddev:635.91 (10.7%) stddev-sans-first:611.12 04:30:20 INFO - PROCESS | 1664 | Values: 3421.5 3455.0 3482.4 3492.2 3521.8 5642.2 5654.5 5680.6 5727.1 5762.0 5706.1 5772.0 5775.0 5779.6 5786.1 5154.9 5190.4 5218.8 5232.4 5275.5 5185.2 5195.3 5203.9 5204.3 5207.5 5180.1 5186.0 5193.2 5196.9 5208.2 5288.7 5295.1 5310.2 5314.0 5353.1 5146.1 5148.2 5152.5 5220.9 5238.9 5590.2 5601.2 5654.2 5661.7 5684.7 6311.7 6317.2 6323.0 6330.2 6337.7 6142.8 6260.4 6334.5 6341.6 6345.3 5881.5 5889.8 5898.2 5915.5 5933.8 6332.4 6333.7 6340.2 6343.7 6348.7 6080.8 6087.3 6118.9 6198.4 6247.4 6322.7 6328.3 6340.1 6340.9 6356.2 6210.3 6272.7 6291.2 6297.5 6320.9 6108.5 6155.5 6170.7 6215.1 6277.8 4944.3 4969.1 4979.2 4986.1 4995.5 4453.6 4492.6 4495.9 4514.8 4552.6 5741.6 5755.7 5773.3 5785.2 5825.6 6234.8 6272.9 6290.3 6297.2 6376.1 5596.2 5663.2 5684.4 5703.5 5749.0 4564.2 5363.8 5365.5 5378.0 5390.1 5508.3 6227.4 6348.0 6388.7 6398.7 6014.3 6217.0 6231.5 6269.7 6280.3 5692.4 6303.7 6334.0 6361.0 6367.9 6138.6 6197.1 6200.9 6211.7 6212.0 5051.3 5723.0 6247.0 6267.2 6330.6 6153.7 6174.1 6178.8 6182.5 6191.8 5160.8 6135.5 6141.5 6153.9 6167.9 5782.8 5858.8 5918.9 5934.6 5942.3 6075.2 6085.2 6101.2 6105.6 6112.4 5900.6 5932.8 5937.0 5944.0 5950.5 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:71252.02 Median:19373.16 stddev:160559.64 (828.8%) stddev-sans-first:160922.14 04:30:20 INFO - PROCESS | 1664 | Values: 486.5 507.1 511.9 516.3 523.0 30003.4 30214.0 42886.6 43116.6 45117.1 44276.9 44717.0 44736.6 44832.1 45001.3 8606.5 8750.3 9630.6 9698.2 9860.9 8611.0 11506.2 11700.8 11726.6 11850.2 11667.2 11703.6 11760.4 11794.8 11801.4 8631.6 11560.4 11749.9 11794.4 11854.9 9978.7 11710.9 11811.5 11841.4 11853.0 39812.0 40067.8 40152.6 40153.6 40197.6 3230.2 3311.6 3337.3 3532.7 3682.9 28378.6 28968.6 29238.6 29635.0 29688.1 2715.8 2728.8 2770.3 2809.5 2857.7 530180.0 530316.0 530732.0 531373.0 532292.0 35687.8 60573.4 60972.7 60997.4 61131.4 36640.5 37790.6 37834.4 37838.0 37926.4 12857.5 15287.8 15350.0 15351.6 15380.0 11630.7 11872.0 12740.0 12861.5 12927.5 8240.2 8251.4 8252.6 8313.8 8322.0 2995.3 3345.9 3459.5 3462.5 3477.7 984.8 986.2 992.9 1072.4 1082.9 27937.0 29807.3 30343.4 30503.8 30608.5 1135.3 1198.4 1221.2 1221.6 1252.9 14816.9 15917.7 15932.0 16024.5 16094.3 657140.0 662066.0 665187.0 666917.0 667362.0 62693.0 63223.7 63230.0 63262.7 63280.4 1324.8 1473.1 1494.3 1519.9 1531.4 29755.0 29880.7 29927.4 30116.8 30247.2 51009.7 51099.0 51823.5 52018.5 52168.7 585785.0 595250.0 597155.0 599109.0 599942.0 23116.7 25521.0 26179.0 26344.6 27112.9 37318.4 43929.0 45409.9 45430.8 46120.8 34567.5 35213.6 36833.4 37643.0 37748.4 36042.6 36349.3 36388.8 37158.8 37166.9 36577.5 37018.3 37161.3 37542.2 37921.1 30444.1 32617.8 32716.8 32772.3 32785.7 19908.9 22258.2 23986.6 24502.4 24592.1 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:31470.06 Median:12359.19 stddev:82978.79 (671.4%) stddev-sans-first:83178.72 04:30:20 INFO - PROCESS | 1664 | Values: 371.3 393.2 396.3 397.1 415.7 20125.4 20240.9 21176.7 21183.3 21215.0 20187.5 20747.5 20831.9 21329.9 21384.0 5290.2 5650.1 5657.9 5688.7 5695.3 5516.3 5610.2 5615.6 5620.5 5688.5 6034.7 6041.2 6061.5 6072.1 6087.4 5432.6 5778.5 6198.4 6673.0 6674.3 5688.6 5836.5 5862.2 5874.3 5875.6 13613.8 13639.0 14043.6 14045.6 14058.2 1479.0 2165.1 2199.8 2289.9 2307.3 9709.0 9741.5 9922.0 10295.6 10456.2 1532.3 1569.2 1710.6 1747.8 1782.3 109149.0 120410.0 127007.0 134471.7 134729.0 11695.8 11741.1 11768.5 11791.2 11997.8 15273.5 15369.6 15370.7 15374.4 15410.5 7305.8 7711.1 8459.0 8770.7 9002.7 8789.3 8797.3 8805.3 8917.8 8934.4 4614.9 4700.3 4711.5 4714.0 4770.4 1672.1 1710.6 1932.4 1957.0 1967.3 924.4 946.6 979.9 1000.4 1013.6 21232.4 22518.5 22620.8 22774.7 22956.5 948.6 964.6 989.8 1016.7 1039.2 8118.2 9442.6 10898.3 11148.7 11283.5 258891.0 266722.0 492708.0 621876.0 628572.0 133709.0 141584.0 144956.6 148981.1 149475.0 1049.4 1064.3 1066.1 1085.1 1086.3 14928.4 18981.6 19005.5 19008.9 19045.8 39354.0 55024.2 56156.6 56901.3 57048.0 80408.2 81629.2 81948.6 82589.0 82903.8 7156.5 7570.7 7627.8 7844.0 8418.7 11690.7 12857.3 12979.4 13372.9 13429.2 11192.0 11270.8 11488.8 11516.9 11612.1 10668.5 10732.8 10888.7 11018.4 11365.3 9810.8 10006.1 11421.1 11544.6 11798.8 6533.7 7095.4 7388.7 7465.4 7984.2 8365.5 11050.6 11257.7 11265.7 11612.2 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5851.62 Median:4890.49 stddev:3333.05 (68.2%) stddev-sans-first:3325.83 04:30:20 INFO - PROCESS | 1664 | Values: 1429.4 1621.3 1652.2 1887.8 2016.4 8074.8 8664.9 8870.5 8873.7 8992.8 8604.5 8843.0 8847.6 8853.0 8856.2 2137.6 2225.5 2366.7 2386.8 2419.2 2259.3 2456.9 2499.0 2499.6 2500.1 2269.5 2273.1 2290.9 2414.0 2471.1 2117.2 2341.8 2402.4 2421.1 2430.3 2430.1 2439.3 2463.8 2473.2 2473.2 9154.0 9214.7 9232.9 9233.4 9279.9 5470.9 5628.0 5645.6 5654.2 5655.9 3601.6 3652.2 3753.0 3759.3 3772.0 988.9 1004.6 1011.2 1011.3 1013.7 12390.1 12392.6 12396.4 12399.3 12401.4 4153.9 4836.0 4883.1 4982.5 5002.0 9210.7 9872.9 10270.2 10289.5 10295.3 5487.8 5546.5 5553.6 5563.2 5564.1 4131.9 4515.5 4674.5 5022.0 5025.7 4035.7 4059.6 4118.4 4169.0 4174.5 1239.3 1247.1 1258.3 1265.0 1294.7 780.8 838.4 878.3 929.0 971.5 4998.7 5148.6 5817.6 5958.7 6164.1 968.8 1016.0 1018.4 1019.3 1070.6 3679.6 3893.7 3904.4 3958.0 4001.2 10965.0 11009.9 11405.4 11419.1 11432.2 9814.7 11207.2 11332.8 11348.9 11367.8 8432.1 8632.6 8634.2 8636.0 8658.9 4711.8 5889.7 5933.3 6082.2 6179.4 10476.8 10623.5 10649.7 10655.5 10662.6 9297.8 9643.9 9749.3 10372.6 10384.7 7058.2 7300.3 7346.6 7698.2 7902.4 8487.5 8619.6 8701.6 8707.4 8709.4 7539.1 7728.5 7785.0 7892.7 7906.3 7536.7 7613.5 7737.7 7779.5 7789.3 7175.8 7251.9 7267.1 7269.1 7276.9 4076.6 4800.1 4897.8 5070.1 5124.9 5007.0 5060.9 5092.9 5169.5 5277.4 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4565.79 Median:5125.23 stddev:1405.44 (27.4%) stddev-sans-first:1372.57 04:30:20 INFO - PROCESS | 1664 | Values: 308.3 318.2 322.4 325.2 329.2 4962.0 5148.0 5418.7 5445.9 5517.5 4891.9 5090.8 5377.3 5395.4 5452.8 3525.3 3724.6 3945.2 3958.3 4030.0 3646.4 4039.7 4075.3 4094.1 4112.9 3560.6 3842.0 4019.6 4092.9 4130.7 3433.2 3760.7 3801.7 3878.1 4118.3 3541.9 3577.6 3578.7 3579.2 3582.7 4734.7 5079.6 5218.6 5221.3 5239.8 4187.7 4374.8 4408.7 4662.0 4664.1 5089.9 5488.5 5633.6 5651.3 5690.8 3377.7 3966.7 3968.7 3969.3 3984.5 4677.9 5789.8 5842.6 5862.7 5975.7 3964.9 3967.3 4009.1 4009.6 4020.8 4049.6 4072.9 4081.6 4086.6 4148.2 3956.2 4719.9 4720.8 4722.6 4732.3 5214.8 5235.7 5490.1 5724.5 5740.3 1163.2 1379.6 1403.5 1410.8 1413.9 860.7 871.1 875.1 901.0 920.7 4549.6 4785.0 5085.8 5212.2 5348.7 5082.7 5310.8 5340.2 5382.3 5542.8 4671.1 5061.4 5104.9 5387.7 5415.9 3058.2 3060.2 3080.4 3082.5 3094.3 5443.0 5910.5 6031.0 6090.8 6155.8 5347.5 5554.7 5696.3 6082.0 6113.8 5593.3 6071.1 6108.6 6139.5 6150.8 5145.5 5209.6 5908.6 5915.7 5940.5 4815.9 5349.1 5804.2 5825.3 5863.7 5523.3 5826.4 6050.1 6133.9 6135.6 2969.7 3268.4 3485.5 4087.8 4135.7 5558.2 5733.1 5799.5 5828.8 5882.6 4643.1 4872.4 5392.8 5692.1 5726.0 4646.5 5384.8 5610.9 5704.8 5710.3 4444.9 4542.5 4635.7 5480.5 5502.9 5349.1 5407.5 5629.2 5635.1 5636.4 5646.1 5647.1 5647.2 5661.5 5669.3 04:30:20 INFO - PROCESS | 1664 | 04:30:20 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18049.03 Median:32756.41 stddev:26021.80 (79.4%) stddev-sans-first:26061.97 04:30:20 INFO - PROCESS | 1664 | Values: 640.4 681.9 727.5 729.0 757.3 14961.3 15175.5 15289.0 15393.5 15420.8 14145.5 14182.6 14183.4 14193.6 14205.4 707.4 731.1 904.6 916.5 937.5 904.2 924.6 924.7 924.7 925.8 974.9 976.7 977.0 977.4 978.9 742.3 763.3 765.5 767.6 970.7 710.2 732.2 732.4 733.9 735.2 14555.6 14605.3 14612.6 14616.9 14657.7 12125.9 12192.0 12469.5 12470.3 12532.9 10699.2 10860.3 10861.2 10899.5 11273.4 3541.4 3790.8 3957.7 4084.8 4101.6 80832.2 81961.6 82475.2 83259.6 83803.2 9407.1 9613.0 9624.7 9778.5 9888.0 21134.5 21142.9 21149.3 21158.6 21179.0 6044.7 7170.9 7210.7 7237.5 7402.6 3954.1 4009.5 4041.4 4062.1 4093.2 3364.8 3458.0 3464.9 3465.1 3468.7 640.1 645.8 655.2 655.9 656.1 461.2 507.0 551.9 563.2 575.9 6798.3 7612.7 7706.5 7903.2 8130.0 535.3 544.2 550.8 566.6 579.2 3716.3 3760.9 3789.3 3873.9 3984.7 81723.8 93099.5 93222.1 93576.0 96356.5 83615.6 83783.2 84222.6 84355.0 84701.6 74361.3 74604.6 74651.6 75364.2 76518.0 7262.5 7292.3 7476.1 7525.8 7753.5 28737.4 29446.9 39303.8 46758.8 48056.8 59903.7 63610.7 64116.0 64867.0 65443.7 9837.7 9851.0 9867.4 9874.6 10093.3 10186.7 10623.6 10769.7 10858.8 11357.7 7889.0 9785.4 10220.0 10224.6 10283.6 11051.1 11126.9 11318.7 11343.9 11826.8 10901.8 11125.1 11362.7 11412.5 12216.1 9446.6 9464.1 9488.5 9677.4 9894.3 9431.3 9602.7 9614.8 9663.4 9668.4 04:30:20 INFO - PROCESS | 1664 | -------- Summary: end -------- 04:30:20 INFO - PROCESS | 1664 | 04:30:21 INFO - TEST-INFO | 1664: exit 0 04:30:21 INFO - TEST-OK | dromaeo_css | took 1117041ms 04:30:21 INFO - TEST-START | kraken 04:30:21 INFO - operating with platform_type : mac_ 04:30:21 INFO - Initialising browser for kraken test... 04:30:21 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpFTXUvb/profile http://localhost:49275/getInfo.html) 04:30:23 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 04:30:23 INFO - PROCESS | 1674 | colorDepth:24 04:30:23 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 04:30:23 INFO - PROCESS | 1674 | __metrics 04:30:23 INFO - PROCESS | 1674 | 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:30:23 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:30:24 INFO - TEST-INFO | 1674: exit 0 04:30:24 INFO - Browser initialized. 04:30:24 INFO - Running cycle 1/1 for kraken test... 04:30:24 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpFTXUvb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:36 INFO - PROCESS | 1676 | RSS: Main: 239419392 04:30:36 INFO - PROCESS | 1676 | 04:30:39 INFO - PROCESS | 1676 | 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:40 INFO - PROCESS | 1676 | RSS: Main: 287297536 04:30:40 INFO - PROCESS | 1676 | 04:30:42 INFO - PROCESS | 1676 | 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:43 INFO - PROCESS | 1676 | RSS: Main: 296361984 04:30:43 INFO - PROCESS | 1676 | 04:30:47 INFO - PROCESS | 1676 | 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:47 INFO - PROCESS | 1676 | RSS: Main: 386547712 04:30:47 INFO - PROCESS | 1676 | 04:30:49 INFO - PROCESS | 1676 | 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:50 INFO - PROCESS | 1676 | RSS: Main: 285376512 04:30:50 INFO - PROCESS | 1676 | 04:30:52 INFO - PROCESS | 1676 | 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:53 INFO - PROCESS | 1676 | RSS: Main: 290508800 04:30:53 INFO - PROCESS | 1676 | 04:30:56 INFO - PROCESS | 1676 | 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:56 INFO - PROCESS | 1676 | RSS: Main: 327360512 04:30:56 INFO - PROCESS | 1676 | 04:30:59 INFO - PROCESS | 1676 | 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:31:00 INFO - PROCESS | 1676 | RSS: Main: 329719808 04:31:00 INFO - PROCESS | 1676 | 04:31:03 INFO - PROCESS | 1676 | 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:31:04 INFO - PROCESS | 1676 | RSS: Main: 328794112 04:31:04 INFO - PROCESS | 1676 | 04:31:05 INFO - PROCESS | 1676 | 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:31:06 INFO - PROCESS | 1676 | RSS: Main: 316588032 04:31:06 INFO - PROCESS | 1676 | 04:31:08 INFO - PROCESS | 1676 | 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:31:08 INFO - PROCESS | 1676 | RSS: Main: 323502080 04:31:08 INFO - PROCESS | 1676 | 04:31:11 INFO - PROCESS | 1676 | 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:31:11 INFO - PROCESS | 1676 | RSS: Main: 325300224 04:31:11 INFO - PROCESS | 1676 | 04:31:14 INFO - PROCESS | 1676 | 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:31:15 INFO - PROCESS | 1676 | RSS: Main: 330055680 04:31:15 INFO - PROCESS | 1676 | 04:31:18 INFO - PROCESS | 1676 | 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:31:19 INFO - PROCESS | 1676 | RSS: Main: 327745536 04:31:19 INFO - PROCESS | 1676 | 04:31:20 INFO - PROCESS | 1676 | 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:31:21 INFO - PROCESS | 1676 | RSS: Main: 374231040 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | __start_tp_report 04:31:21 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 04:31:21 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 04:31:21 INFO - PROCESS | 1676 | |i|pagename|runs| 04:31:21 INFO - PROCESS | 1676 | |0;ai-astar;111;105;111;111;111;111;112;112;118;164 04:31:21 INFO - PROCESS | 1676 | |1;audio-beat-detection;155;121;132;249;135;142;152;111;168;114 04:31:21 INFO - PROCESS | 1676 | |2;audio-dft;187;187;185;184;185;183;182;184;183;182 04:31:21 INFO - PROCESS | 1676 | |3;audio-fft;78;74;57;125;58;61;56;73;104;55 04:31:21 INFO - PROCESS | 1676 | |4;audio-oscillator;93;92;134;78;77;77;102;78;80;100 04:31:21 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;89;85;87;85;88;85;88;85;88;85 04:31:21 INFO - PROCESS | 1676 | |6;imaging-darkroom;93;93;93;93;93;92;94;95;93;92 04:31:21 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;86;83;83;83;86;83;86;83;84 04:31:21 INFO - PROCESS | 1676 | |8;json-parse-financial;57;58;58;59;58;56;57;120;54;55 04:31:21 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;58;66;62;72;71;66;66;68;66;66 04:31:21 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;107;105;106;105;98;85;84;86;83;86 04:31:21 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;135;138;138;143;148;132;134;134;214;142 04:31:21 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;161;161;161;217;216;163;165;166;164;159 04:31:21 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;59;63;82;67;62;60;64;85;68;64 04:31:21 INFO - PROCESS | 1676 | __end_tp_report 04:31:21 INFO - PROCESS | 1676 | __start_cc_report 04:31:21 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2244 04:31:21 INFO - PROCESS | 1676 | __end_cc_report 04:31:21 INFO - PROCESS | 1676 | __startTimestamp1463484681198__endTimestamp 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | ------- Summary: start ------- 04:31:21 INFO - PROCESS | 1676 | Number of tests: 14 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:116.60 Median:111.50 stddev:16.94 (15.2%) stddev-sans-first:17.85 04:31:21 INFO - PROCESS | 1676 | Values: 111.0 105.0 111.0 111.0 111.0 111.0 112.0 112.0 118.0 164.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:147.00 stddev:39.97 (27.2%) stddev-sans-first:42.32 04:31:21 INFO - PROCESS | 1676 | Values: 155.0 121.0 132.0 249.0 135.0 142.0 152.0 111.0 168.0 114.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:1.81 (1.0%) stddev-sans-first:1.62 04:31:21 INFO - PROCESS | 1676 | Values: 187.0 187.0 185.0 184.0 185.0 183.0 182.0 184.0 183.0 182.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:74.10 Median:59.50 stddev:23.37 (39.3%) stddev-sans-first:24.75 04:31:21 INFO - PROCESS | 1676 | Values: 78.0 74.0 57.0 125.0 58.0 61.0 56.0 73.0 104.0 55.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:78.00 stddev:17.97 (23.0%) stddev-sans-first:19.05 04:31:21 INFO - PROCESS | 1676 | Values: 93.0 92.0 134.0 78.0 77.0 77.0 102.0 78.0 80.0 100.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.65 (1.9%) stddev-sans-first:1.48 04:31:21 INFO - PROCESS | 1676 | Values: 89.0 85.0 87.0 85.0 88.0 85.0 88.0 85.0 88.0 85.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 04:31:21 INFO - PROCESS | 1676 | Values: 93.0 93.0 93.0 93.0 93.0 92.0 94.0 95.0 93.0 92.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.40 (1.7%) stddev-sans-first:1.45 04:31:21 INFO - PROCESS | 1676 | Values: 85.0 86.0 83.0 83.0 83.0 86.0 83.0 86.0 83.0 84.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:57.50 stddev:20.02 (34.8%) stddev-sans-first:21.10 04:31:21 INFO - PROCESS | 1676 | Values: 57.0 58.0 58.0 59.0 58.0 56.0 57.0 120.0 54.0 55.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:4.01 (6.1%) stddev-sans-first:3.00 04:31:21 INFO - PROCESS | 1676 | Values: 58.0 66.0 62.0 72.0 71.0 66.0 66.0 68.0 66.0 66.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:84.50 stddev:10.53 (12.5%) stddev-sans-first:10.15 04:31:21 INFO - PROCESS | 1676 | Values: 107.0 105.0 106.0 105.0 98.0 85.0 84.0 86.0 83.0 86.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:140.00 stddev:24.46 (17.5%) stddev-sans-first:25.63 04:31:21 INFO - PROCESS | 1676 | Values: 135.0 138.0 138.0 143.0 148.0 132.0 134.0 134.0 214.0 142.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:164.50 stddev:22.87 (13.9%) stddev-sans-first:23.82 04:31:21 INFO - PROCESS | 1676 | Values: 161.0 161.0 161.0 217.0 216.0 163.0 165.0 166.0 164.0 159.0 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:65.50 stddev:8.95 (13.7%) stddev-sans-first:8.96 04:31:21 INFO - PROCESS | 1676 | Values: 59.0 63.0 82.0 67.0 62.0 60.0 64.0 85.0 68.0 64.0 04:31:21 INFO - PROCESS | 1676 | -------- Summary: end -------- 04:31:21 INFO - PROCESS | 1676 | 04:31:21 INFO - TEST-INFO | 1676: exit 0 04:31:22 INFO - TEST-OK | kraken | took 60634ms 04:31:22 INFO - SUITE-END | took 1177s 04:31:22 INFO - Completed test suite (00:19:38) 04:31:22 INFO - javascript benchmark 04:31:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3421.4696722008084, 3454.965450345496, 3482.355764183628, 3492.1793378556035, 3521.7886926784395, 5642.15367694846, 5654.491095801377, 5680.573956953224, 5727.112297593874, 5761.971190144049, 5706.087026075828, 5772.0187568113415, 5775.0, 5779.62432441891, 5786.132080187974, 5154.871128221796, 5190.377154741417, 5218.791248350061, 5232.372115346164, 5275.525202731754, 5185.222216667492, 5195.298634684307, 5203.9219411708855, 5204.297419848329, 5207.453217412174, 5180.067587834188, 5186.014657215117, 5193.16909294513, 5196.948030519703, 5208.192730126818, 5288.709120998344, 5295.099833028395, 5310.20346947957, 5314.0, 5353.143497040455, 5146.125158722989, 5148.150555158394, 5152.458991805881, 5220.947790522084, 5238.92141617876, 5590.18942253375, 5601.215829783833, 5654.180143879114, 5661.716914154317, 5684.687342196177, 6311.684415779238, 6317.210348706411, 6322.968385158045, 6330.208723909512, 6337.651429171394, 6142.815715528542, 6260.373962603749, 6334.493240540792, 6341.587796793194, 6345.33373995733, 5881.4706676399755, 5889.793857214977, 5898.203742494751, 5915.497182739429, 5933.792317268875, 6332.398422149889, 6333.68331583419, 6340.239171299474, 6343.714532846033, 6348.746050158034, 6080.787172448943, 6087.281609052182, 6118.862843290023, 6198.411150940653, 6247.406496382836, 6322.71547780351, 6328.303886572473, 6340.1440805490665, 6340.936590634127, 6356.173697419306, 6210.347913469111, 6272.717727702265, 6291.213598300213, 6297.4647154992335, 6320.936790632127, 6108.493847774667, 6155.538334624832, 6170.691465426711, 6215.12988181655, 6277.780277690259, 4944.283078953532, 4969.130402179604, 4979.153543897546, 4986.102501549683, 4995.500449954975, 4453.643708503312, 4492.595666390041, 4495.887602809956, 4514.841980530666, 4552.590266875997, 5741.62679425836, 5755.712214389264, 5773.336066352424, 5785.161151633074, 5825.563082768809, 6234.781782637677, 6272.874542509125, 6290.339514350927, 6297.2128483939505, 6376.075469057054, 5596.1605759136455, 5663.235463212413, 5684.431556844365, 5703.486686198262, 5748.965186266512, 4564.2240819060835, 5363.758630861621, 5365.463453654681, 5378.0, 5390.110631745796, 5508.256385388001, 6227.408396202353, 6347.968260158762, 6388.744450221939, 6398.680065996775, 6014.3159915223805, 6217.0, 6231.501479881689, 6269.686515674107, 6280.27776805655, 5692.402297758676, 6303.684815759286, 6334.018227174795, 6360.968195158995, 6367.872642547216, 6138.631682099089, 6197.132401463708, 6200.906986395298, 6211.689415529115, 6212.0, 5051.292819005415, 5722.998475266729, 6247.0, 6267.1539342188225, 6330.620162790247, 6153.692315384303, 6174.104754810617, 6178.783742568989, 6182.536309776695, 6191.783287584913, 5160.767765450489, 6135.53983451234, 6141.508679305734, 6153.907691384722, 6167.876642467215, 5782.768689252551, 5858.794942177174, 5918.940810591839, 5934.643921364732, 5942.346341902474, 6075.2405949256345, 6085.178500902499, 6101.176341194059, 6105.572609917441, 6112.358202388685, 5900.557458190567, 5932.79235226765, 5936.970315148396, 5943.959807033665, 5950.5239580834295], "value": 5708.49314691111, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.45031114840225, 507.14545990006866, 511.91474074961087, 516.2926790297292, 522.9529342359187, 30003.399932001346, 30214.0, 42886.57113428865, 43116.56883431165, 45117.09765804682, 44276.89307767308, 44717.0, 44736.55263447373, 44832.10335793282, 45001.324980125246, 8606.536888728931, 8750.256228220609, 9630.614775408993, 9698.224142068617, 9860.901390986106, 8611.0, 11506.194566380356, 11700.824487632692, 11726.648200553997, 11850.229735067236, 11667.24162929411, 11703.648890533255, 11760.353180575064, 11794.7641047179, 11801.409929503534, 8631.568421578895, 11560.421978901106, 11749.941250293736, 11794.410279485992, 11854.881451185507, 9978.650747223812, 11710.94144529272, 11811.527538898434, 11841.40792960357, 11852.992495637882, 39812.0, 40067.7996610018, 40152.598474015176, 40153.598464015275, 40197.59802401997, 3230.2247460609283, 3311.586051743532, 3337.332533493311, 3532.6643968822923, 3682.9447558286647, 28378.57432138499, 28968.565471517944, 29238.56142157869, 29635.0, 29688.109356719335, 2715.7507546528623, 2728.7953403494816, 2770.3074231442138, 2809.5083360411845, 2857.7142285771465, 530180.0, 530316.0, 530731.9999999962, 531373.0, 532292.0, 35687.821560892546, 60573.39426605678, 60972.69513652492, 60997.39002609917, 61131.38868611346, 36640.45039324412, 37790.62209377871, 37834.43248351277, 37838.0, 37926.43110353347, 12857.485700571873, 15287.770683439758, 15350.002249853724, 15351.61620959485, 15380.0, 11630.6510804676, 11872.0, 12740.044496662787, 12861.485540578458, 12927.5475358362, 8240.21717936795, 8251.422400431911, 8252.587370631445, 8313.833723325499, 8321.95839020801, 2995.2961054152256, 3345.9498107528407, 3459.4810778383444, 3462.4806279058344, 3477.7217822574135, 984.7833476635129, 986.1913231150388, 992.8957459466797, 1072.3940973350188, 1082.868402519369, 27937.0, 29807.254818629273, 30343.393132137677, 30503.779848806244, 30608.54087188694, 1135.2847705945367, 1198.3888217009214, 1221.2367270455966, 1221.5724496426358, 1252.9248245105323, 14816.92591537057, 15917.681646367007, 15932.044077355396, 16024.519264422086, 16094.275757590936, 657140.0, 662066.0, 665187.0, 666917.0, 667362.0, 62693.0, 63223.683881581215, 63230.0, 63262.73474530576, 63280.36719632837, 1324.7615429222833, 1473.0940471609667, 1494.3424893047024, 1519.931603077842, 1531.4104069932932, 29755.0, 29880.70119298779, 29927.401451971273, 30116.831683168315, 30247.158471029892, 51009.744951275, 51099.0, 51823.48176518337, 52018.47981520136, 52168.73915630549, 585785.0, 595250.0, 597155.0, 599109.0, 599942.0, 23116.653250200925, 25521.0, 26179.0, 26344.60483092717, 27112.864435678486, 37318.440223397214, 43929.0, 45409.864753380105, 45430.77284613556, 46120.769396152806, 34567.4814877772, 35213.64786352206, 36833.44749828701, 37643.0, 37748.43377349287, 36042.63957360393, 36349.27301454009, 36388.81805590955, 37158.8142059288, 37166.88499345024, 36577.45133822942, 37018.25963480769, 37161.25677486381, 37542.2491550162, 37921.05197370088, 30444.086677399715, 32617.836910815295, 32716.83641581777, 32772.34455310833, 32785.672143278265, 19908.90045549763, 22258.22096226624, 23986.640200396658, 24502.387440314134, 24592.142002393583], "value": 19035.780389878404, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.3260432315348, 393.2430072111186, 396.259312093834, 397.14811728841653, 415.67577289714035, 20125.396238112862, 20240.89879550604, 21176.682349764767, 21183.258585949494, 21215.045322960465, 20187.495312617193, 20747.481312967182, 20831.895840520814, 21329.93350332485, 21384.0, 5290.153575427932, 5650.067738823089, 5657.915131273035, 5688.687122208277, 5695.316562012565, 5516.3380394352735, 5610.214569960209, 5615.578831587647, 5620.522255608258, 5688.488036076752, 6034.728437220336, 6041.214642096521, 6061.515078793708, 6072.08918662202, 6087.391260873921, 5432.619716619838, 5778.5377169826315, 6198.380161983833, 6673.0, 6674.3325667433355, 5688.63023903445, 5836.503897168745, 5862.179294898718, 5874.295084589834, 5875.588708790387, 13613.795793063113, 13639.0, 14043.578692639237, 14045.578632641038, 14058.226797526131, 1478.9926050369786, 2165.0798410675557, 2199.82401407887, 2289.8740569268684, 2307.3428615644225, 9708.951455242679, 9741.512924353825, 9922.0, 10295.639652612197, 10456.215783816167, 1532.256855425118, 1569.199708148841, 1710.5894585299563, 1747.8339557741995, 1782.2514543891466, 109149.0, 120409.98975025695, 127007.0, 134471.6552834469, 134729.0, 11695.824562631567, 11741.060715142768, 11768.52925882955, 11791.233569817934, 11997.76004479923, 15273.465428710164, 15369.615759606098, 15370.692586148438, 15374.385024599114, 15410.460633877761, 7305.817354566072, 7711.149324162161, 8459.027211870714, 8770.736877893672, 9002.68490602839, 8789.340799440068, 8797.340199485065, 8805.295576353876, 8917.821643567093, 8934.374593778502, 4614.861554153381, 4700.26090346574, 4711.481737008926, 4714.0, 4770.427548694179, 1672.1355688407582, 1710.5638062294283, 1932.4009557037364, 1956.9823691680247, 1967.3409407848244, 924.3945215883607, 946.5929650250321, 979.9412035277763, 1000.429755039635, 1013.614826365991, 21232.36302910915, 22518.549629007328, 22620.773792262196, 22774.65838012431, 22956.54086918252, 948.606328373731, 964.5852283518154, 989.8416253399565, 1016.7305663999047, 1039.2205845615788, 8118.188181181953, 9442.583612458186, 10898.346099234072, 11148.665540033811, 11283.54865805375, 258891.0, 266722.0, 492708.0, 621876.0, 628572.0, 133709.0, 141584.0, 144956.55043449643, 148981.08151347865, 149475.0, 1049.41757324684, 1064.324154162097, 1066.1364294921138, 1085.1102096280974, 1086.266769930294, 14928.402863885323, 18981.620367592295, 19005.524861878563, 19008.904955475686, 19045.809541904957, 39354.0, 55024.17463737967, 56156.5960850982, 56901.30141540786, 57048.0, 80408.19591804007, 81629.18370816454, 81948.59025704833, 82589.0, 82903.7564436546, 7156.463265255023, 7570.659320330488, 7627.771166865003, 7844.0, 8418.663253470038, 11690.70773230676, 12857.292848893401, 12979.351032448529, 13372.93313533426, 13429.194248345131, 11191.99272065518, 11270.830937536108, 11488.770224595628, 11516.94241528787, 11612.071034317403, 10668.519916603616, 10732.785344293226, 10888.727781805519, 11018.39398833072, 11365.31808091517, 9810.75473113178, 10006.09945104944, 11421.086313095098, 11544.59593914209, 11798.823017654571, 6533.67331633426, 7095.361417472453, 7388.741394051182, 7465.402767778456, 7984.24149705777, 8365.456245344034, 11050.557977680803, 11257.71855703614, 11265.662030139109, 11612.245204061708], "value": 9393.525006354577, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.4496618801759, 1621.3352525464559, 1652.1739130434783, 1887.820657037582, 2016.395081475557, 8074.838503229932, 8664.913350866489, 8870.512121833297, 8873.73378798637, 8992.775180620487, 8604.483730976146, 8843.0, 8847.646094156242, 8852.981907080688, 8856.202941735259, 2137.6045431595126, 2225.532638145983, 2366.692329997098, 2386.809055275578, 2419.2016634510655, 2259.3334966185016, 2456.9017239310406, 2498.950020999579, 2499.6125600531946, 2500.1374525788574, 2269.546090781842, 2273.068042102738, 2290.874001929893, 2413.9758602414013, 2471.1104002559064, 2117.17430202222, 2341.840788809533, 2402.3994001499627, 2421.128393778238, 2430.2952143878256, 2430.1251549442186, 2439.2682195341326, 2463.790577800889, 2473.1591258972035, 2473.1591258972035, 9154.038825923315, 9214.723558293263, 9232.907670923272, 9233.39982901116, 9279.907200927972, 5470.890582188374, 5628.0, 5645.633033852787, 5654.151877218409, 5655.943440565624, 3601.58581763098, 3652.1782598915297, 3753.0429740416293, 3759.3233218020746, 3772.0, 988.8566157907063, 1004.6182450668699, 1011.2415688233824, 1011.3072545306488, 1013.6857574152036, 12390.132690711655, 12392.56626018085, 12396.380180991004, 12399.318037507934, 12401.379931003414, 4153.854615088458, 4835.95164048362, 4883.145449546315, 4982.476839931827, 5001.999600079998, 9210.723678289662, 9872.901270987199, 10270.229732770073, 10289.485525723683, 10295.279330446796, 5487.75305111271, 5546.500814926676, 5553.555715542747, 5563.193336966117, 5564.081926482085, 4131.9173616528105, 4515.458145022619, 4674.509176536482, 5022.020705962375, 5025.698458092526, 4035.6966749494654, 4059.573744756758, 4118.402831589463, 4169.020280234143, 4174.519930208005, 1239.3369547292154, 1247.1332423965257, 1258.3205069262679, 1265.0, 1294.70869054462, 780.8399278147966, 838.3544670603601, 878.2774684245469, 928.9721308360624, 971.5482300730195, 4998.6750861193905, 5148.588112950955, 5817.592768506249, 5958.702064896738, 6164.075388691643, 968.8207681578931, 1015.9898401016043, 1018.4042335233823, 1019.2864994503877, 1070.5878236878857, 3679.5952445231005, 3893.6690381317903, 3904.375299951994, 3957.9802100989887, 4001.1797581495725, 10965.0, 11009.889901101047, 11405.372704501166, 11419.143564232716, 11432.199746017699, 9814.65648702292, 11207.159463040141, 11332.830007549894, 11348.886511134948, 11367.829482557769, 8432.114627964098, 8632.568371581396, 8634.179752923588, 8635.963684358043, 8658.87011694825, 4711.764411779466, 5889.734961926809, 5933.2880054393745, 6082.239720034996, 6179.351168127282, 10476.790464190826, 10623.521941512496, 10649.680509584725, 10655.454959030742, 10662.573497060032, 9297.814043718952, 9643.9035609643, 9749.268804839523, 10372.636957706443, 10384.688459346231, 7058.223595404399, 7300.306470885257, 7346.6326683666675, 7698.230176982257, 7902.407319451179, 8487.490750554987, 8619.569021549023, 8701.608427620646, 8707.434016789142, 8709.38876307885, 7539.061914446047, 7728.536287822749, 7784.9610751945875, 7892.723754668559, 7906.288434040964, 7536.660850261642, 7613.505122167041, 7737.6518056686455, 7779.494332868346, 7789.33790627803, 7175.784726458214, 7251.927480725336, 7267.091613548307, 7269.055022846996, 7276.927230727625, 4076.571959944163, 4800.135975524439, 4897.8285759998225, 5070.062038522885, 5124.948750512447, 5007.048660754445, 5060.949390506195, 5092.898142037065, 5169.508896654812, 5277.393099793419], "value": 4678.390973031497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.3293835906902, 318.15212458797305, 322.38102842542315, 325.1951420234919, 329.18855022369854, 4961.950380496194, 5148.0, 5418.701971391577, 5445.945540544593, 5517.475839795218, 4891.926621100686, 5090.770915308811, 5377.274068000819, 5395.352557693082, 5452.754626041828, 3525.294941011795, 3724.5902950675404, 3945.191235796655, 3958.3270843956598, 4029.9798501007454, 3646.3618866698353, 4039.676825853939, 4075.286824805662, 4094.0992981544014, 4112.938305925413, 3560.590532088817, 3841.9807900960595, 4019.638232559069, 4092.85675001375, 4130.690198235129, 3433.1931995980926, 3760.7179461540495, 3801.74542401007, 3878.1495600733297, 4118.279301122307, 3541.8937431877084, 3577.6422357764277, 3578.6738619897014, 3579.158897659048, 3582.6775590197008, 4734.739589322586, 5079.644424890259, 5218.608604354688, 5221.347331583552, 5239.768654366221, 4187.706860519765, 4374.781260936972, 4408.7354758714255, 4661.953380466186, 4664.113818374498, 5089.949100508985, 5488.4785945335125, 5633.57748168889, 5651.32184137902, 5690.841582921477, 3377.6791204835745, 3966.6513385449084, 3968.7023473239624, 3969.325214713506, 3984.4620976168135, 4677.906441871178, 5789.8263052108505, 5842.649441033509, 5862.736176872051, 5975.731092100866, 3964.920701585952, 3967.274235707419, 4009.115715613657, 4009.599040095967, 4020.798960052044, 4049.635532802003, 4072.857449989295, 4081.591840815954, 4086.6117718816663, 4148.1792381181085, 3956.228535435562, 4719.905601887943, 4720.763961801897, 4722.645801564828, 4732.320026390651, 5214.7914083436235, 5235.685858848481, 5490.121580547173, 5724.542036637059, 5740.253767010262, 1163.2148299897704, 1379.6275005748391, 1403.5368328451586, 1410.7883817427469, 1413.922234277104, 860.7245681381897, 871.0984131424073, 875.1205038935828, 900.9683911920945, 920.742192186189, 4549.5905368517, 4784.952150478521, 5085.796568137233, 5212.192110222922, 5348.679079255257, 5082.695038297714, 5310.7610157543, 5340.225667278223, 5382.30030096085, 5542.8337149885565, 4671.11248862715, 5061.4179369373, 5104.948950510522, 5387.730613469311, 5415.918761218585, 3058.1742929408933, 3060.1890499017777, 3080.414721203009, 3082.460569400346, 3094.272845881216, 5442.9930462864495, 5910.463279547263, 6030.969845150834, 6090.817275481742, 6155.753769849245, 5347.545458635982, 5554.666719996813, 5696.3449203341315, 6081.969590152109, 6113.84715382119, 5593.300837395325, 6071.148299768613, 6108.5723999319625, 6139.47814435769, 6150.753969841246, 5145.507802737314, 5209.609279303992, 5908.586398952032, 5915.674637894957, 5940.46535811779, 4815.879603009953, 5349.144136938071, 5804.158397032491, 5825.271841019872, 5863.736131874161, 5523.342997100934, 5826.42686474658, 6050.09595394222, 6133.877322453615, 6135.570510064431, 2969.658489273761, 3268.362669279468, 3485.4946032825605, 4087.836486540505, 4135.731177473454, 5558.249636299048, 5733.0540460823495, 5799.4780469757925, 5828.825135245949, 5882.617629854046, 4643.099796171233, 4872.4153101628035, 5392.840539284073, 5692.117721753135, 5726.0, 4646.488886222581, 5384.784608615768, 5610.943890561042, 5704.828855134353, 5710.2576665033275, 4444.911101778011, 4542.477615074176, 4635.65232607549, 5480.506754392124, 5502.945865044939, 5349.144136938226, 5407.513323800877, 5629.155626656035, 5635.126555383922, 5636.408177141505, 5646.096624540054, 5647.124695672177, 5647.199880101881, 5661.46216109469, 5669.3480249770155], "value": 4122.947698823095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.3564417760152, 681.9147606549179, 727.5416487612803, 728.9963550182248, 757.3146302596152, 14961.326740296685, 15175.544733657995, 15289.0, 15393.538193854203, 15420.845791542095, 14145.504907328246, 14182.645433864158, 14183.361748721307, 14193.645158871033, 14205.360758765855, 707.4305184326615, 731.0605871455181, 904.6200595749768, 916.5005072235614, 937.5359197197397, 904.2268860124598, 924.5654542365111, 924.7410724997012, 924.7456949338952, 925.842606756853, 974.8878878928907, 976.6825781620967, 977.0376179463259, 977.4184360305642, 978.872746542952, 742.3319012888389, 763.2939530933864, 765.5368502056288, 767.5586537740766, 970.7330484116853, 710.232948415715, 732.2274999875129, 732.3847967707098, 733.9082614673166, 735.1509007096812, 14555.563333099917, 14605.342759575844, 14612.561623151216, 14616.926915365462, 14657.70684586313, 12125.878741212651, 12192.0, 12469.52072590638, 12470.314132722697, 12532.874671253261, 10699.19756018294, 10860.3483790972, 10861.1854110942, 10899.509522071527, 11273.37996410197, 3541.415666415064, 3790.7915064671433, 3957.742746721454, 4084.7549147051272, 4101.569335219819, 80832.19167808305, 81961.59019204926, 82475.17524824735, 83259.58370208171, 83803.16196838014, 9407.106324899123, 9613.0, 9624.66313679018, 9778.511074446249, 9887.950560247153, 21134.47163820917, 21142.942852857297, 21149.259775908078, 21158.576828463345, 21179.0, 6044.727987240584, 7170.92829071713, 7210.711571537185, 7237.493375463667, 7402.592857392788, 3954.070793363557, 4009.51885773709, 4041.4341992121126, 4062.0657248832936, 4093.2222877653153, 3364.8149351785396, 3457.9827100864836, 3464.8787292444645, 3465.081753335368, 3468.687818096333, 640.1038546035494, 645.8127143128534, 655.1875674164097, 655.885220086483, 656.1371796088129, 461.2343509773759, 506.994648389822, 551.9448055194448, 563.2199403825649, 575.8761866198787, 6798.286179951133, 7612.657430415645, 7706.460547761715, 7903.249191326815, 8130.0, 535.3228166369546, 544.248936467672, 550.7796881247453, 566.6005466146439, 579.2035950567969, 3716.2939041582554, 3760.9435858462143, 3789.317922773927, 3873.9418908716393, 3984.6812255019527, 81723.7741433879, 93099.5345023284, 93222.06777932134, 93576.0, 96356.51821740846, 83615.58192209122, 83783.16216837753, 84222.57888710518, 84355.0, 84701.57649211715, 74361.25638743652, 74604.62697686476, 74651.62674186703, 75364.24635753682, 76518.0, 7262.527935684164, 7292.307230813064, 7476.102867655699, 7525.8118547036765, 7753.457257991887, 28737.42525149527, 29446.85276573689, 39303.80348098356, 46758.76620616875, 48056.759716201195, 59903.70048149731, 63610.68194658997, 64116.0, 64867.0, 65443.67278163579, 9837.70486885366, 9851.0, 9867.40795552269, 9874.61438949155, 10093.293469457067, 10186.694399168036, 10623.575056997943, 10769.730756731144, 10858.837117443087, 11357.716057098638, 7888.967247640869, 9785.412875227508, 10220.02909723575, 10224.64213752515, 10283.640072597722, 11051.115910727283, 11126.888731112585, 11318.660440186808, 11343.886561134283, 11826.82259766087, 10901.83647245276, 11125.109991200523, 11362.659120226406, 11412.48643811047, 12216.14486985938, 9446.622135114518, 9464.1009104135, 9488.478133702713, 9677.419354838732, 9894.257930655085, 9431.339806213498, 9602.66390676351, 9614.807703846023, 9663.371880827724, 9668.419894806053], "value": 6724.480977402234, "unit": "score"}], "name": "dromaeo_css", "value": 7139.280705405331, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 111.0, 111.0, 111.0, 112.0, 112.0, 118.0, 164.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 132.0, 249.0, 135.0, 142.0, 152.0, 111.0, 168.0, 114.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 185.0, 184.0, 185.0, 183.0, 182.0, 184.0, 183.0, 182.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 57.0, 125.0, 58.0, 61.0, 56.0, 73.0, 104.0, 55.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 134.0, 78.0, 77.0, 77.0, 102.0, 78.0, 80.0, 100.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 85.0, 88.0, 85.0, 88.0, 85.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 94.0, 95.0, 93.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 83.0, 83.0, 86.0, 83.0, 86.0, 83.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 59.0, 58.0, 56.0, 57.0, 120.0, 54.0, 55.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 66.0, 62.0, 72.0, 71.0, 66.0, 66.0, 68.0, 66.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 105.0, 106.0, 105.0, 98.0, 85.0, 84.0, 86.0, 83.0, 86.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 138.0, 143.0, 148.0, 132.0, 134.0, 134.0, 214.0, 142.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 217.0, 216.0, 163.0, 165.0, 166.0, 164.0, 159.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 82.0, 67.0, 62.0, 60.0, 64.0, 85.0, 68.0, 64.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1488.0, "alertThreshold": 2.0}]} 04:31:22 INFO - Return code: 0 04:31:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:31:22 ERROR - # TBPL SUCCESS # 04:31:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:31:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:31:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:31:22 INFO - 2016-05-17 04:31:22.482 screenresolution[1680:10138] starting screenresolution argv=screenresolution get 04:31:22 INFO - 2016-05-17 04:31:22.485 screenresolution[1680:10138] Display 0: 1600x1200x32@60 04:31:22 INFO - 2016-05-17 04:31:22.491 screenresolution[1681:10141] starting screenresolution argv=screenresolution list 04:31:22 INFO - Available Modes on Display 0 04:31:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:31:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:31:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:31:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:31:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:31:22 INFO - Intel Iris: 04:31:22 INFO - Chipset Model: Intel Iris 04:31:22 INFO - Type: GPU 04:31:22 INFO - Bus: Built-In 04:31:22 INFO - VRAM (Dynamic, Max): 1536 MB 04:31:22 INFO - Vendor: Intel (0x8086) 04:31:22 INFO - Device ID: 0x0a2e 04:31:22 INFO - Revision ID: 0x0009 04:31:22 INFO - Displays: 04:31:22 INFO - Display: 04:31:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:31:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:31:22 INFO - Display Serial Number: 1600x1200 60 04:31:22 INFO - Main Display: Yes 04:31:22 INFO - Mirror: Off 04:31:22 INFO - Online: Yes 04:31:22 INFO - Rotation: Supported 04:31:22 INFO - Return code: 0 04:31:22 INFO - Running post-action listener: _resource_record_post_action 04:31:22 INFO - ##### 04:31:22 INFO - ##### Finished run-tests step (success) 04:31:22 INFO - ##### 04:31:22 INFO - Running post-run listener: _resource_record_post_run 04:31:22 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37910016; Write bytes: 695045120; Read time: 452; Write time: 1967 04:31:22 INFO - TinderboxPrint: CPU usage
53.7% 04:31:22 INFO - TinderboxPrint: I/O read bytes / time
37,910,016 / 452 04:31:22 INFO - TinderboxPrint: I/O write bytes / time
695,045,120 / 1,967 04:31:22 INFO - TinderboxPrint: CPU idle
2,246.2 (46.3%) 04:31:22 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 04:31:22 INFO - TinderboxPrint: CPU user
1,637.1 (33.7%) 04:31:22 INFO - TinderboxPrint: Swap in / out
432,803,840 / 0 04:31:22 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 378499584; Write bytes: 373016576; Read time: 28150; Write time: 769 04:31:22 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36955648; Write bytes: 297149440; Read time: 438; Write time: 1144 04:31:23 INFO - Running post-run listener: _upload_blobber_files 04:31:23 INFO - Blob upload gear active. 04:31:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:31:23 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:31:23 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:31:23 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:31:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:31:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:31:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:31:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:24 INFO - (blobuploader) - INFO - Done attempting. 04:31:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:31:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:31:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:25 INFO - (blobuploader) - INFO - Done attempting. 04:31:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:31:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:31:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:31:28 INFO - (blobuploader) - INFO - Done attempting. 04:31:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:31:28 INFO - Return code: 0 04:31:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:31:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:31:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b113df54c48b1753f4c3eb8de54dee3b3e4c1b99e471ca377f8b83fd0a44f9f3b818e259a84b8051658ddb69e636268c4f68ee2c0d9f1166bfcf29c4fffbe6ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd79fe3bbb13de92f348f3b35fd82c2238021799b2e93b2787be03096b0f41ea9641bd3a69c044fb66087f95bd701ea92bdd08da63ca19a5ffde05a82d39ad3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/188ffbe735b8e5f533ebb433b80816dbd27a5cd82db51091b5da7ab57ff01ecc07215d755734562a08383fb58ff0fb0af9f2676b5597bec46a1562d3515500a2"} 04:31:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:31:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:31:28 INFO - Contents: 04:31:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b113df54c48b1753f4c3eb8de54dee3b3e4c1b99e471ca377f8b83fd0a44f9f3b818e259a84b8051658ddb69e636268c4f68ee2c0d9f1166bfcf29c4fffbe6ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd79fe3bbb13de92f348f3b35fd82c2238021799b2e93b2787be03096b0f41ea9641bd3a69c044fb66087f95bd701ea92bdd08da63ca19a5ffde05a82d39ad3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/188ffbe735b8e5f533ebb433b80816dbd27a5cd82db51091b5da7ab57ff01ecc07215d755734562a08383fb58ff0fb0af9f2676b5597bec46a1562d3515500a2"} 04:31:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:31:28 INFO - Copying logs to upload dir... 04:31:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:31:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.000240 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-17 04:31:28.601574) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 04:31:28.605882) ========= 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.wpGx8GQsrP/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.2MghzUNj2T/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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/b113df54c48b1753f4c3eb8de54dee3b3e4c1b99e471ca377f8b83fd0a44f9f3b818e259a84b8051658ddb69e636268c4f68ee2c0d9f1166bfcf29c4fffbe6ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd79fe3bbb13de92f348f3b35fd82c2238021799b2e93b2787be03096b0f41ea9641bd3a69c044fb66087f95bd701ea92bdd08da63ca19a5ffde05a82d39ad3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/188ffbe735b8e5f533ebb433b80816dbd27a5cd82db51091b5da7ab57ff01ecc07215d755734562a08383fb58ff0fb0af9f2676b5597bec46a1562d3515500a2"} build_url:https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011449 build_url: 'https://queue.taskcluster.net/v1/task/CqJctSsQSOqcR72rOi-GrA/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/b113df54c48b1753f4c3eb8de54dee3b3e4c1b99e471ca377f8b83fd0a44f9f3b818e259a84b8051658ddb69e636268c4f68ee2c0d9f1166bfcf29c4fffbe6ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd79fe3bbb13de92f348f3b35fd82c2238021799b2e93b2787be03096b0f41ea9641bd3a69c044fb66087f95bd701ea92bdd08da63ca19a5ffde05a82d39ad3d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/188ffbe735b8e5f533ebb433b80816dbd27a5cd82db51091b5da7ab57ff01ecc07215d755734562a08383fb58ff0fb0af9f2676b5597bec46a1562d3515500a2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 04:31:28.640152) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 04:31:28.640521) ========= 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.wpGx8GQsrP/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.2MghzUNj2T/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005149 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 04:31:28.689293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 04:31:28.689681) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 04:31:28.690076) ========= ========= Total master_lag: 1.23 =========