builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0231 starttime: 1463425872.34 results: success (0) buildid: 20160516101243 builduid: e05f4bca960d4bb3a7291f6e22777b5a revision: 9426bf3851462c728243b5b5866489805b31e088 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.345104) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.345551) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.345828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.365212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.365483) ========= 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.lfj00EcIfp/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.FxQIUfZhva/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-16 12:11: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% 18.4M=0.001s 2016-05-16 12:11:12 (18.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110979 ========= master_lag: 0.50 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.980544) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:12.980854) ========= 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.lfj00EcIfp/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.FxQIUfZhva/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.024315 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:13.020931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 12:11:13.021257) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9426bf3851462c728243b5b5866489805b31e088 --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 9426bf3851462c728243b5b5866489805b31e088 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lfj00EcIfp/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.FxQIUfZhva/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-16 12:11:13,033 truncating revision to first 12 chars 2016-05-16 12:11:13,033 Setting DEBUG logging. 2016-05-16 12:11:13,033 attempt 1/10 2016-05-16 12:11:13,033 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9426bf385146?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 12:11:14,287 unpacking tar archive at: fx-team-9426bf385146/testing/mozharness/ program finished with exit code 0 elapsedTime=1.504825 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 12:11:14.542861) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:14.543180) ========= script_repo_revision: 9426bf3851462c728243b5b5866489805b31e088 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:14.546601) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:14.546868) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 12:11:14.561146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-16 12:11:14.561426) ========= /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.lfj00EcIfp/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.FxQIUfZhva/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11:14 INFO - MultiFileLogger online at 20160516 12:11:14 in /builds/slave/test 12:11: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 12:11:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:11:14 INFO - {'append_to_log': False, 12:11:14 INFO - 'base_work_dir': '/builds/slave/test', 12:11:14 INFO - 'blob_upload_branch': 'Fx-Team', 12:11:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:11:14 INFO - 'branch': 'Fx-Team', 12:11:14 INFO - 'buildbot_json_path': 'buildprops.json', 12:11:14 INFO - 'config_files': ('talos/mac_config.py',), 12:11:14 INFO - 'default_actions': ('clobber', 12:11:14 INFO - 'read-buildbot-config', 12:11:14 INFO - 'download-and-extract', 12:11:14 INFO - 'populate-webroot', 12:11:14 INFO - 'create-virtualenv', 12:11:14 INFO - 'install', 12:11:14 INFO - 'run-tests'), 12:11:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:11:14 INFO - 'download_minidump_stackwalk': True, 12:11:14 INFO - 'download_symbols': 'ondemand', 12:11:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:11:14 INFO - 'tooltool.py': '/tools/tooltool.py', 12:11:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:11:14 INFO - '/tools/misc-python/virtualenv.py')}, 12:11:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:11:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:11:14 INFO - 'installer_path': 'installer.exe', 12:11:14 INFO - 'log_level': 'info', 12:11:14 INFO - 'log_name': 'talos', 12:11:14 INFO - 'log_to_console': True, 12:11:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:11:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:11:14 INFO - 'opt_config_files': (), 12:11:14 INFO - 'pip_index': False, 12:11:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:11:14 INFO - 'cmd': ('bash', 12:11:14 INFO - '-c', 12:11:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:11:14 INFO - 'enabled': True, 12:11:14 INFO - 'halt_on_failure': False, 12:11:14 INFO - 'name': 'check_screen_resolution'},), 12:11:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:11:14 INFO - 'cmd': ('bash', 12:11:14 INFO - '-c', 12:11:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:11:14 INFO - 'enabled': True, 12:11:14 INFO - 'halt_on_failure': False, 12:11:14 INFO - 'name': 'check_screen_resolution'},), 12:11:14 INFO - 'run_cmd_checks_enabled': True, 12:11:14 INFO - 'sps_profile': False, 12:11:14 INFO - 'sps_profile_interval': 0, 12:11:14 INFO - 'suite': 'dromaeojs', 12:11:14 INFO - 'system_bits': '32', 12:11:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:11:14 INFO - 'title': 't-yosemite-r7-0231', 12:11:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:11:14 INFO - 'use_talos_json': True, 12:11:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:11:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:11:14 INFO - 'work_dir': 'build'} 12:11:14 INFO - ##### 12:11:14 INFO - ##### Running clobber step. 12:11:14 INFO - ##### 12:11:14 INFO - Running pre-action listener: _resource_record_pre_action 12:11:14 INFO - Running main action method: clobber 12:11:14 INFO - rmtree: /builds/slave/test/build 12:11:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:11:16 INFO - Running post-action listener: _resource_record_post_action 12:11:16 INFO - ##### 12:11:16 INFO - ##### Finished clobber step (success) 12:11:16 INFO - ##### 12:11:16 INFO - ##### 12:11:16 INFO - ##### Running read-buildbot-config step. 12:11:16 INFO - ##### 12:11:16 INFO - Running pre-action listener: _resource_record_pre_action 12:11:16 INFO - Running main action method: read_buildbot_config 12:11:16 INFO - Using buildbot properties: 12:11:16 INFO - { 12:11:16 INFO - "project": "", 12:11:16 INFO - "product": "firefox", 12:11:16 INFO - "script_repo_revision": "production", 12:11:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:11:16 INFO - "repository": "", 12:11:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:11:16 INFO - "buildid": "20160516101243", 12:11:16 INFO - "pgo_build": "False", 12:11:16 INFO - "basedir": "/builds/slave/test", 12:11:16 INFO - "buildnumber": 819, 12:11:16 INFO - "slavename": "t-yosemite-r7-0231", 12:11:16 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:11:16 INFO - "platform": "yosemite_r7", 12:11:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:11:16 INFO - "branch": "fx-team", 12:11:16 INFO - "repo_path": "integration/fx-team", 12:11:16 INFO - "revision": "9426bf3851462c728243b5b5866489805b31e088", 12:11:16 INFO - "stage_platform": "macosx64", 12:11:16 INFO - "builduid": "e05f4bca960d4bb3a7291f6e22777b5a", 12:11:16 INFO - "slavebuilddir": "test" 12:11:16 INFO - } 12:11:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:11:16 INFO - Running post-action listener: _resource_record_post_action 12:11:16 INFO - ##### 12:11:16 INFO - ##### Finished read-buildbot-config step (success) 12:11:16 INFO - ##### 12:11:16 INFO - ##### 12:11:16 INFO - ##### Running download-and-extract step. 12:11:16 INFO - ##### 12:11:16 INFO - Running pre-action listener: _resource_record_pre_action 12:11:16 INFO - Running main action method: download_and_extract 12:11:16 INFO - mkdir: /builds/slave/test/build/tests 12:11: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')]} 12:11:16 INFO - https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:11:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:11:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:11:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:18 INFO - Downloaded 1199 bytes. 12:11:18 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:11:18 INFO - Using the following test package requirements: 12:11:18 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:11:18 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:11:18 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:11:18 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'jsshell-mac.zip'], 12:11:18 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:11:18 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:11:18 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:11:18 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:11:18 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:11:18 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:11:18 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:11:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:11:18 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')]} 12:11:18 INFO - https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:11:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:11:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:11:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:20 INFO - Downloaded 18267736 bytes. 12:11:20 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'] 12:11:20 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 12:11:23 INFO - Return code: 0 12:11:23 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 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:23 INFO - https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:11:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:11:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:11:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:24 INFO - Downloaded 18267736 bytes. 12:11:24 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'] 12:11:24 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 12:11:27 INFO - Return code: 0 12:11: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')]} 12:11:27 INFO - https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:11:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:11:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:11:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:29 INFO - Downloaded 10840194 bytes. 12:11:29 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'] 12:11:29 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 12:11:29 INFO - Return code: 0 12:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:29 INFO - https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:11:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:11:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:11:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:11:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TUcHInpwRW6bCLwNAQgPkw/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 12:11:33 INFO - Downloaded 110047969 bytes. 12:11:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:11:33 INFO - mkdir: /builds/slave/test/properties 12:11:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:33 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:33 INFO - Contents: 12:11:33 INFO - build_url:https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:11:34 INFO - Running post-action listener: _resource_record_post_action 12:11:34 INFO - Running post-action listener: set_extra_try_arguments 12:11:34 INFO - ##### 12:11:34 INFO - ##### Finished download-and-extract step (success) 12:11:34 INFO - ##### 12:11:34 INFO - ##### 12:11:34 INFO - ##### Running populate-webroot step. 12:11:34 INFO - ##### 12:11:34 INFO - Running pre-action listener: _resource_record_pre_action 12:11:34 INFO - Running main action method: populate_webroot 12:11:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:34 INFO - '--tppagecycles', 12:11:34 INFO - '7'], 12:11:34 INFO - 'tests': ['tsvgm']}}, 12:11:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['damp', 'tps']}, 12:11:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'tests': ['damp', 'tps']}, 12:11:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['dromaeo_dom']}, 12:11:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['a11yr', 12:11:34 INFO - 'ts_paint', 12:11:34 INFO - 'tpaint', 12:11:34 INFO - 'sessionrestore', 12:11:34 INFO - 'sessionrestore_no_auto_restore', 12:11:34 INFO - 'tabpaint']}, 12:11:34 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:34 INFO - 'ts_paint', 12:11:34 INFO - 'tpaint', 12:11:34 INFO - 'sessionrestore', 12:11:34 INFO - 'sessionrestore_no_auto_restore', 12:11:34 INFO - 'tabpaint']}, 12:11:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:34 INFO - 'ts_paint', 12:11:34 INFO - 'tpaint', 12:11:34 INFO - 'sessionrestore', 12:11:34 INFO - 'sessionrestore_no_auto_restore', 12:11:34 INFO - 'tabpaint']}, 12:11:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:34 INFO - 'ts_paint', 12:11:34 INFO - 'tpaint', 12:11:34 INFO - 'sessionrestore', 12:11:34 INFO - 'sessionrestore_no_auto_restore', 12:11:34 INFO - 'tabpaint']}, 12:11:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['a11yr', 12:11:34 INFO - 'ts_paint', 12:11:34 INFO - 'tpaint', 12:11:34 INFO - 'sessionrestore', 12:11:34 INFO - 'sessionrestore_no_auto_restore', 12:11:34 INFO - 'tabpaint']}, 12:11:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['a11yr', 12:11:34 INFO - 'ts_paint', 12:11:34 INFO - 'tpaint', 12:11:34 INFO - 'sessionrestore', 12:11:34 INFO - 'sessionrestore_no_auto_restore', 12:11:34 INFO - 'tabpaint']}, 12:11:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['tsvgx', 12:11:34 INFO - 'tsvgr_opacity', 12:11:34 INFO - 'tart', 12:11:34 INFO - 'tscrollx', 12:11:34 INFO - 'cart']}, 12:11:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:34 INFO - 'tsvgr_opacity', 12:11:34 INFO - 'tart', 12:11:34 INFO - 'tscrollx', 12:11:34 INFO - 'cart']}, 12:11:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'talos_options': ['--disable-e10s'], 12:11:34 INFO - 'tests': ['tp5o']}, 12:11:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'tests': ['tp5o']}, 12:11:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'talos_options': ['--disable-e10s', 12:11:34 INFO - '--xperf_path', 12:11:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:34 INFO - 'tests': ['tp5n']}, 12:11:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:34 INFO - 'talos_options': ['--xperf_path', 12:11:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:34 INFO - 'tests': ['tp5n']}}, 12:11:34 INFO - 'talos.zip': {'path': '', 12:11:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:34 INFO - Running post-action listener: _resource_record_post_action 12:11:34 INFO - ##### 12:11:34 INFO - ##### Finished populate-webroot step (success) 12:11:34 INFO - ##### 12:11:34 INFO - ##### 12:11:34 INFO - ##### Running create-virtualenv step. 12:11:34 INFO - ##### 12:11:34 INFO - Running pre-action listener: _resource_record_pre_action 12:11:34 INFO - Running main action method: create_virtualenv 12:11:34 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:34 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 12:11:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:34 INFO - Using real prefix '/tools/python27' 12:11:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:35 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:38 INFO - Installing pip.................done. 12:11:38 INFO - Return code: 0 12:11:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11: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 12:11: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 12:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:38 INFO - 'HOME': '/Users/cltbld', 12:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:38 INFO - 'LOGNAME': 'cltbld', 12:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:38 INFO - 'MOZ_NO_REMOTE': '1', 12:11:38 INFO - 'NO_EM_RESTART': '1', 12:11:38 INFO - 'PAGER': '/bin/cat', 12:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:38 INFO - 'PWD': '/builds/slave/test', 12:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:38 INFO - 'SHELL': '/bin/bash', 12:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:11:38 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:38 INFO - 'USER': 'cltbld', 12:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:38 INFO - 'XPC_FLAGS': '0x0', 12:11:38 INFO - 'XPC_SERVICE_NAME': '0', 12:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11: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 12:11:38 INFO - Cleaning up... 12:11:38 INFO - Return code: 0 12:11:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11: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 12:11: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 12:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:38 INFO - 'HOME': '/Users/cltbld', 12:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:38 INFO - 'LOGNAME': 'cltbld', 12:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:38 INFO - 'MOZ_NO_REMOTE': '1', 12:11:38 INFO - 'NO_EM_RESTART': '1', 12:11:38 INFO - 'PAGER': '/bin/cat', 12:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:38 INFO - 'PWD': '/builds/slave/test', 12:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:38 INFO - 'SHELL': '/bin/bash', 12:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:11:38 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:38 INFO - 'USER': 'cltbld', 12:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:38 INFO - 'XPC_FLAGS': '0x0', 12:11:38 INFO - 'XPC_SERVICE_NAME': '0', 12:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:38 INFO - Downloading/unpacking psutil>=3.1.1 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:41 INFO - Installing collected packages: psutil 12:11:41 INFO - Running setup.py install for psutil 12:11:41 INFO - building 'psutil._psutil_osx' extension 12:11: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 12:11: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 12:11: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 12:11: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 12:11:42 INFO - building 'psutil._psutil_posix' extension 12:11: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 12:11:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:42 INFO - ^ 12:11:42 INFO - 1 warning generated. 12:11: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 12:11:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:42 INFO - Successfully installed psutil 12:11:42 INFO - Cleaning up... 12:11:42 INFO - Return code: 0 12:11:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12: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')]} 12:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12: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')]} 12:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:42 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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11:42 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 12:11:42 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 12:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:42 INFO - 'HOME': '/Users/cltbld', 12:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:42 INFO - 'LOGNAME': 'cltbld', 12:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:42 INFO - 'MOZ_NO_REMOTE': '1', 12:11:42 INFO - 'NO_EM_RESTART': '1', 12:11:42 INFO - 'PAGER': '/bin/cat', 12:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:42 INFO - 'PWD': '/builds/slave/test', 12:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:42 INFO - 'SHELL': '/bin/bash', 12:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:11:42 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:42 INFO - 'USER': 'cltbld', 12:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:42 INFO - 'XPC_FLAGS': '0x0', 12:11:42 INFO - 'XPC_SERVICE_NAME': '0', 12:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:11:42 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 12:11:42 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 12:11:42 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 12:11:42 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 12:11:42 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 12:11:42 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 12:11:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:11:45 INFO - Installing collected packages: mozsystemmonitor 12:11:45 INFO - Running setup.py install for mozsystemmonitor 12:11:45 INFO - Successfully installed mozsystemmonitor 12:11:45 INFO - Cleaning up... 12:11:45 INFO - Return code: 0 12:11:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11:45 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')]} 12:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:45 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')]} 12:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:45 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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11:45 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 12:11:45 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 12:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:45 INFO - 'HOME': '/Users/cltbld', 12:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:45 INFO - 'LOGNAME': 'cltbld', 12:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:45 INFO - 'MOZ_NO_REMOTE': '1', 12:11:45 INFO - 'NO_EM_RESTART': '1', 12:11:45 INFO - 'PAGER': '/bin/cat', 12:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:45 INFO - 'PWD': '/builds/slave/test', 12:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:45 INFO - 'SHELL': '/bin/bash', 12:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:11:45 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:45 INFO - 'USER': 'cltbld', 12:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:45 INFO - 'XPC_FLAGS': '0x0', 12:11:45 INFO - 'XPC_SERVICE_NAME': '0', 12:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:45 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:48 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:49 INFO - Downloading docopt-0.6.1.tar.gz 12:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:49 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:49 INFO - Running setup.py install for blobuploader 12:11:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:49 INFO - Running setup.py install for requests 12:11:50 INFO - Running setup.py install for docopt 12:11:50 INFO - Successfully installed blobuploader requests docopt 12:11:50 INFO - Cleaning up... 12:11:50 INFO - Return code: 0 12:11:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11: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 12:11: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 12:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:50 INFO - 'HOME': '/Users/cltbld', 12:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:50 INFO - 'LOGNAME': 'cltbld', 12:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:50 INFO - 'MOZ_NO_REMOTE': '1', 12:11:50 INFO - 'NO_EM_RESTART': '1', 12:11:50 INFO - 'PAGER': '/bin/cat', 12:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:50 INFO - 'PWD': '/builds/slave/test', 12:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:50 INFO - 'SHELL': '/bin/bash', 12:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:11:50 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:50 INFO - 'USER': 'cltbld', 12:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:50 INFO - 'XPC_FLAGS': '0x0', 12:11:50 INFO - 'XPC_SERVICE_NAME': '0', 12:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:50 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-XqYYez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:50 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-rFH0rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:50 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-W3mmo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:50 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-qmXH2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-MjddCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-72LSZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-YQCMX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-SLSBFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-4BGuHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-8J78cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-FGhMSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-zZEkr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:51 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-yCAE6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:52 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-6Rlst1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:52 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-VZ4OnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:52 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-TFIi4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:52 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-885Nhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:52 INFO - Running setup.py install for manifestparser 12:11:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:52 INFO - Running setup.py install for mozcrash 12:11:52 INFO - Running setup.py install for mozdebug 12:11:52 INFO - Running setup.py install for mozdevice 12:11:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Running setup.py install for mozfile 12:11:53 INFO - Running setup.py install for mozhttpd 12:11:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Running setup.py install for mozinfo 12:11:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Running setup.py install for mozInstall 12:11:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Running setup.py install for mozleak 12:11:53 INFO - Running setup.py install for mozlog 12:11:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:53 INFO - Running setup.py install for moznetwork 12:11:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:54 INFO - Running setup.py install for mozprocess 12:11:54 INFO - Running setup.py install for mozprofile 12:11:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:54 INFO - Running setup.py install for mozrunner 12:11:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:54 INFO - Running setup.py install for mozscreenshot 12:11:54 INFO - Running setup.py install for moztest 12:11:54 INFO - Running setup.py install for mozversion 12:11:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:54 INFO - Cleaning up... 12:11:55 INFO - Return code: 0 12:11:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:11: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 12:11: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 12:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:55 INFO - 'HOME': '/Users/cltbld', 12:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:55 INFO - 'LOGNAME': 'cltbld', 12:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:55 INFO - 'MOZ_NO_REMOTE': '1', 12:11:55 INFO - 'NO_EM_RESTART': '1', 12:11:55 INFO - 'PAGER': '/bin/cat', 12:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:55 INFO - 'PWD': '/builds/slave/test', 12:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:55 INFO - 'SHELL': '/bin/bash', 12:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:11:55 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:55 INFO - 'USER': 'cltbld', 12:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:55 INFO - 'XPC_FLAGS': '0x0', 12:11:55 INFO - 'XPC_SERVICE_NAME': '0', 12:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-A6blYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11: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)) 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-bxoNs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11: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)) 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Qla8U1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:55 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)) 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-0VEnrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:55 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)) 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-K0f3EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:55 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)) 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-KgTrza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:55 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)) 12:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:55 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-UvpzVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11: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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-RBAKQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11: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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-mMR0Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11: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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-a9K6FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11: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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-7Uq0sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11: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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-9hyqi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:56 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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-zrDQT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:56 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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-UHi0ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:56 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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-BlAS16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:56 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)) 12:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:56 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Opognp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11: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)) 12:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:57 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-bhhQ5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11: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)) 12:11: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)) 12:11: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)) 12:11: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)) 12:11: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)) 12:11: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)) 12:11:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11: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 12:11:59 INFO - Downloading blessings-1.6.tar.gz 12:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:59 INFO - Installing collected packages: blessings 12:11:59 INFO - Running setup.py install for blessings 12:11:59 INFO - Successfully installed blessings 12:11:59 INFO - Cleaning up... 12:12:00 INFO - Return code: 0 12:12:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:00 INFO - Reading from file tmpfile_stdout 12:12:00 INFO - Current package versions: 12:12:00 INFO - blessings == 1.6 12:12:00 INFO - blobuploader == 1.2.4 12:12:00 INFO - docopt == 0.6.1 12:12:00 INFO - manifestparser == 1.1 12:12:00 INFO - mozInstall == 1.12 12:12:00 INFO - mozcrash == 0.17 12:12:00 INFO - mozdebug == 0.1 12:12:00 INFO - mozdevice == 0.48 12:12:00 INFO - mozfile == 1.2 12:12:00 INFO - mozhttpd == 0.7 12:12:00 INFO - mozinfo == 0.9 12:12:00 INFO - mozleak == 0.1 12:12:00 INFO - mozlog == 3.2 12:12:00 INFO - moznetwork == 0.27 12:12:00 INFO - mozprocess == 0.22 12:12:00 INFO - mozprofile == 0.28 12:12:00 INFO - mozrunner == 6.11 12:12:00 INFO - mozscreenshot == 0.1 12:12:00 INFO - mozsystemmonitor == 0.3 12:12:00 INFO - moztest == 0.7 12:12:00 INFO - mozversion == 1.4 12:12:00 INFO - psutil == 3.1.1 12:12:00 INFO - requests == 1.2.3 12:12:00 INFO - wsgiref == 0.1.2 12:12:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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')]} 12:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:12: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 12:12: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 12:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:00 INFO - 'HOME': '/Users/cltbld', 12:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:00 INFO - 'LOGNAME': 'cltbld', 12:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:00 INFO - 'MOZ_NO_REMOTE': '1', 12:12:00 INFO - 'NO_EM_RESTART': '1', 12:12:00 INFO - 'PAGER': '/bin/cat', 12:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:00 INFO - 'PWD': '/builds/slave/test', 12:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:00 INFO - 'SHELL': '/bin/bash', 12:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:12:00 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:00 INFO - 'USER': 'cltbld', 12:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:00 INFO - 'XPC_FLAGS': '0x0', 12:12:00 INFO - 'XPC_SERVICE_NAME': '0', 12:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12: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)) 12:12:00 INFO - Cleaning up... 12:12:00 INFO - Return code: 0 12:12:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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')]} 12:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:12: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 12:12: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 12:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:00 INFO - 'HOME': '/Users/cltbld', 12:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:00 INFO - 'LOGNAME': 'cltbld', 12:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:00 INFO - 'MOZ_NO_REMOTE': '1', 12:12:00 INFO - 'NO_EM_RESTART': '1', 12:12:00 INFO - 'PAGER': '/bin/cat', 12:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:00 INFO - 'PWD': '/builds/slave/test', 12:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:00 INFO - 'SHELL': '/bin/bash', 12:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:12:00 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:00 INFO - 'USER': 'cltbld', 12:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:00 INFO - 'XPC_FLAGS': '0x0', 12:12:00 INFO - 'XPC_SERVICE_NAME': '0', 12:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:00 INFO - Downloading/unpacking jsonschema 12:12:00 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 12:12:00 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 12:12:00 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 12:12:00 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 12:12:00 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 12:12:00 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 12:12:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:12:03 INFO - Downloading/unpacking functools32 (from jsonschema) 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12:03 INFO - Downloading functools32-3.2.3-2.tar.gz 12:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:12:03 INFO - warning: no files found matching '*.txt' 12:12:03 INFO - no previously-included directories found matching 'build' 12:12:03 INFO - no previously-included directories found matching 'dist' 12:12:03 INFO - no previously-included directories found matching '.git*' 12:12:03 INFO - Installing collected packages: jsonschema, functools32 12:12:03 INFO - Running setup.py install for functools32 12:12:04 INFO - warning: no files found matching '*.txt' 12:12:04 INFO - no previously-included directories found matching 'build' 12:12:04 INFO - no previously-included directories found matching 'dist' 12:12:04 INFO - no previously-included directories found matching '.git*' 12:12:04 INFO - Successfully installed jsonschema functools32 12:12:04 INFO - Cleaning up... 12:12:04 INFO - Return code: 0 12:12:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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')]} 12:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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 0x10e8b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9190d94220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, '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.lfj00EcIfp/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.FxQIUfZhva/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 12:12: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 12:12: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 12:12:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:12:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:04 INFO - 'HOME': '/Users/cltbld', 12:12:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:04 INFO - 'LOGNAME': 'cltbld', 12:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:04 INFO - 'MOZ_NO_REMOTE': '1', 12:12:04 INFO - 'NO_EM_RESTART': '1', 12:12:04 INFO - 'PAGER': '/bin/cat', 12:12:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:04 INFO - 'PWD': '/builds/slave/test', 12:12:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:04 INFO - 'SHELL': '/bin/bash', 12:12:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:12:04 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:12:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:04 INFO - 'USER': 'cltbld', 12:12:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:04 INFO - 'XPC_FLAGS': '0x0', 12:12:04 INFO - 'XPC_SERVICE_NAME': '0', 12:12:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:05 INFO - Downloading/unpacking flake8 12:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:12:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:12:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:12:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:12:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:12:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:12:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:12:09 INFO - no previously-included directories found matching 'docs/_build' 12:12:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:12:09 INFO - Running setup.py install for pep8 12:12:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:12:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:12:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:12:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:12:09 INFO - no previously-included directories found matching 'docs/_build' 12:12:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:12:09 INFO - Cleaning up... 12:12:09 INFO - Return code: 0 12:12:09 INFO - Running post-action listener: _resource_record_post_action 12:12:09 INFO - Running post-action listener: _start_resource_monitoring 12:12:09 INFO - Starting resource monitoring. 12:12:09 INFO - ##### 12:12:09 INFO - ##### Finished create-virtualenv step (success) 12:12:09 INFO - ##### 12:12:09 INFO - ##### 12:12:09 INFO - ##### Running install step. 12:12:09 INFO - ##### 12:12:09 INFO - Running pre-action listener: _resource_record_pre_action 12:12:09 INFO - Running main action method: install 12:12:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:09 INFO - Reading from file tmpfile_stdout 12:12:09 INFO - Detecting whether we're running mozinstall >=1.0... 12:12:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:12:09 INFO - Reading from file tmpfile_stdout 12:12:09 INFO - Output received: 12:12:09 INFO - Usage: mozinstall [options] installer 12:12:09 INFO - Options: 12:12:09 INFO - -h, --help show this help message and exit 12:12:09 INFO - -d DEST, --destination=DEST 12:12:09 INFO - Directory to install application into. [default: 12:12:09 INFO - "/builds/slave/test"] 12:12:09 INFO - --app=APP Application being installed. [default: firefox] 12:12:09 INFO - mkdir: /builds/slave/test/build/application 12:12: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'] 12:12: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 12:12:45 INFO - Reading from file tmpfile_stdout 12:12:45 INFO - Output received: 12:12:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:12:45 INFO - Running post-action listener: _resource_record_post_action 12:12:45 INFO - ##### 12:12:45 INFO - ##### Finished install step (success) 12:12:45 INFO - ##### 12:12:45 INFO - ##### 12:12:45 INFO - ##### Running run-tests step. 12:12:45 INFO - ##### 12:12:45 INFO - Running pre-action listener: _resource_record_pre_action 12:12:45 INFO - Running main action method: run_tests 12:12:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:45 INFO - 2016-05-16 12:12:45.227 screenresolution[1636:7513] starting screenresolution argv=screenresolution get 12:12:45 INFO - 2016-05-16 12:12:45.230 screenresolution[1636:7513] Display 0: 1600x1200x32@60 12:12:45 INFO - 2016-05-16 12:12:45.236 screenresolution[1637:7515] starting screenresolution argv=screenresolution list 12:12:45 INFO - Available Modes on Display 0 12:12:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:45 INFO - Intel Iris: 12:12:45 INFO - Chipset Model: Intel Iris 12:12:45 INFO - Type: GPU 12:12:45 INFO - Bus: Built-In 12:12:45 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:45 INFO - Vendor: Intel (0x8086) 12:12:45 INFO - Device ID: 0x0a2e 12:12:45 INFO - Revision ID: 0x0009 12:12:45 INFO - Displays: 12:12:45 INFO - Display: 12:12:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:45 INFO - Display Serial Number: 1600x1200 60 12:12:45 INFO - Main Display: Yes 12:12:45 INFO - Mirror: Off 12:12:45 INFO - Online: Yes 12:12:45 INFO - Rotation: Supported 12:12:45 INFO - Return code: 0 12:12:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:45 INFO - Python 2.7.3 12:12:45 INFO - Return code: 0 12:12:45 INFO - grabbing minidump binary from tooltool 12:12:45 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')]} 12:12:45 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 0x10e75dd30>, '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 0x10e581db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e754b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:45 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 12:12:45 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 12:12:45 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 12:12:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:45 INFO - Return code: 0 12:12:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:12:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:12:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:12:46 INFO - Return code: 0 12:12:46 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/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--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 12:12:46 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/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0231 --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 12:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lfj00EcIfp/Render', 12:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:46 INFO - 'HOME': '/Users/cltbld', 12:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:46 INFO - 'LOGNAME': 'cltbld', 12:12:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:46 INFO - 'MOZ_NO_REMOTE': '1', 12:12:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:46 INFO - 'NO_EM_RESTART': '1', 12:12:46 INFO - 'PAGER': '/bin/cat', 12:12:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:46 INFO - 'PWD': '/builds/slave/test', 12:12:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:46 INFO - 'SHELL': '/bin/bash', 12:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxQIUfZhva/Listeners', 12:12:46 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 12:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:46 INFO - 'USER': 'cltbld', 12:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:46 INFO - 'XPC_FLAGS': '0x0', 12:12:46 INFO - 'XPC_SERVICE_NAME': '0', 12:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:46 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/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--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 12:12:46 INFO - mozversion application_buildid: 20160516101243 12:12:46 INFO - mozversion application_changeset: 9426bf3851462c728243b5b5866489805b31e088 12:12:46 INFO - mozversion application_display_name: Nightly 12:12:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:46 INFO - mozversion application_name: Firefox 12:12:46 INFO - mozversion application_remotingname: firefox 12:12:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:12:46 INFO - mozversion application_vendor: Mozilla 12:12:46 INFO - mozversion application_version: 49.0a1 12:12:46 INFO - mozversion platform_buildid: 20160516101243 12:12:46 INFO - mozversion platform_changeset: 9426bf3851462c728243b5b5866489805b31e088 12:12:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:12:46 INFO - mozversion platform_version: 49.0a1 12:12:46 INFO - using testdate: 1463425966 12:12:46 INFO - actual date: 1463425966 12:12:46 INFO - starting webserver on 'localhost:49275' 12:12:46 INFO - SUITE-START | Running 2 tests 12:12:46 INFO - TEST-START | dromaeo_css 12:12:46 INFO - operating with platform_type : mac_ 12:12:46 INFO - Initialising browser for dromaeo_css test... 12:12:46 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmp6mJSU5/profile http://localhost:49275/getInfo.html) 12:12:49 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:12:49 INFO - PROCESS | 1655 | colorDepth:24 12:12:49 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:12:49 INFO - PROCESS | 1655 | __metrics 12:12:49 INFO - PROCESS | 1655 | 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" 12:12:49 ERROR - PROCESS | 1655 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:12:50 INFO - TEST-INFO | 1655: exit 0 12:12:50 INFO - Browser initialized. 12:12:50 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:50 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmp6mJSU5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:01 INFO - PROCESS | 1660 | RSS: Main: 239177728 12:13:01 INFO - PROCESS | 1660 | 12:13:52 INFO - PROCESS | 1660 | 1463426032791 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] 12:16:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:16:06 INFO - PROCESS | 1660 | RSS: Main: 406020096 12:16:06 INFO - PROCESS | 1660 | 12:19:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:19:10 INFO - PROCESS | 1660 | RSS: Main: 374132736 12:19:10 INFO - PROCESS | 1660 | 12:22:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:22:12 INFO - PROCESS | 1660 | RSS: Main: 369491968 12:22:12 INFO - PROCESS | 1660 | 12:25:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:25:15 INFO - PROCESS | 1660 | RSS: Main: 368513024 12:25:15 INFO - PROCESS | 1660 | 12:28:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:28:19 INFO - PROCESS | 1660 | RSS: Main: 337379328 12:28:19 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:31:22 INFO - PROCESS | 1660 | RSS: Main: 373768192 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | __start_tp_report 12:31:22 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 12:31:22 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 12:31:22 INFO - PROCESS | 1660 | |i|pagename|runs| 12:31:22 INFO - PROCESS | 1660 | |0;dojo.html;3460.15226269564;3466.809325487098;3483.49489324048;3484.7038001769847;3537.6910543099057;5787.855303617412;5790.739416726247;5805.129230615405;5895.292564892214;5899.321578018524;5825.388334224898;5838.21184140142;5885.264341957255;5959.970200149004;5977.1034344848185;5117.769700363474;5158.509941555546;5200.401953775307;5202.8699282518055;5314.627976041678;5260.395054568716;5288.814891478799;5296.841094767144;5325.387580428262;5325.653832500894;5161.664491808039;5164.561012313958;5183.403908550527;5223.6343455958095;5270.446603106676;5190.844274671766;5197.7401129943355;5214.374275086976;5290.867728306785;5324.440933701944;5201.115810312256;5202.687838729688;5211.687298762048;5237.292965449654;5286.1013627683005;5552.888942221133;5654.830355089354;5803.651780893118;5821.970890145564;5896.882062358772;5274.4198138204765;6089.317984404817;6100.298465676459;6110.144579758838;6115.113308570232;6197.03945888388;6200.410960958747;6217.222847144107;6241.906371404432;6242.15730876335;5809.912851307234;5949.583529152961;5996.914962432213;6017.037274036134;6043.365446628129;6189.442950134509;6237.126802247689;6242.812715618538;6325.240971083499;6359.046143078575;6169.167162433104;6175.135481032659;6179.3820617937845;6190.164327815777;6194.783182588588;6129.233845769279;6148.247749391402;6155.230596175478;6214.658193799384;6227.19046523949;6111.13596929516;6120.816375508742;6123.465646421124;6131.693415329216;6133.73253493014;6119.2350956130485;6125.84685382869;6147.969260153671;6152.938470615237;6154.5076393888385;4866.902661946812;4913.533214344631;4944.085344211332;4962.255661650781;4974.527419895104;4403.3174857897075;4417.3815665806815;4419.602235798793;4440.22296098188;4466.665000125004;5843.18195452637;5876.353601103875;5889.764409423575;5892.823215303548;5897.734601942923;6111;6113.724882380304;6140.4473597376455;6175.44421002112;6179.443850053517;5770.394108618535;5809.186713860063;5823.767049317979;5832.650040997553;5847.853803654858;5399.244105825188;5399.784008639689;5412.079946409041;5413.485718856702;5455.263539422207;6344.809655710336;6346.238451385863;6356.269029061532;6362.618242905441;6370.394812492806;6112.17485639433;6115.419035191828;6118.710550942667;6191.566590338633;6211.720472578654;6255.124282600348;6322.525810564134;6326.304106548184;6355.968220159055;6406.743730250924;6108.175396321618;6138.693065346625;6147.262328520606;6160.630362178284;6161.907571386343;6112.449879510866;6144.078388241799;6228.439440450381;6228.750849966132;6241.251049874044;5970.0746384310505;6098.0086717533295;6107.61364822091;6131.509479241739;6141.232345956755;6109.083637454418;6132.693365331626;6142.959235178615;6149.200603921461;6157.876842463215;6099.037411928083;6116.901675877084;6130.795626845537;6132.693365331805;6144.357926091645;6118.071005858243;6119.84700382494;6125.928510892795;6130.291143915101;6137.987232106831;5975.312839023568;6064.787732429344;6131.988221943329;6148.647297594522;6155.199824022849 12:31:22 INFO - PROCESS | 1660 | |1;ext.html;497.2615665736382;501.7215445427788;534.2413772443131;559.5216090242842;563.2030676592622;31346.68653313469;41609.58390416099;42628.786856065715;42781.93045173872;44636.776816115955;43713.90715232121;43861.78069109658;43899;43910;43919;8619.784505387353;9661.903380966205;9826.4104153751;9826.459544725038;9840.655577054804;8685.826283474327;10023.799524009515;11804.822927656092;11825.462689850307;11917.344546049964;11781.823272650918;11803.940980295087;11857.881421185764;11875.7624847503;11878.7624247515;8690;11601.245919015237;11704.7659046819;11789.7642047159;11826.586069487612;11687;11801.937825595653;11802.291862488279;11804.763904721858;11813.586524471688;39654;39945.60054399477;39975.40036899449;40021.79989100065;40261.59738402608;3158.7473002159772;3270.1661076425357;3392.4911263310455;3435.4503279475416;3470.7803309233354;28046.71953280482;28369.432611347656;28654.283642908886;28947.56578651322;29292.560611590845;2698.3658840172543;2703.202555246207;2719;2754.7520723135012;2818.549032154866;480205;499964;500377;501080;501308;30083.69916300853;58675.41324586785;59603.70198149068;59607;59763.70118149468;36146.09634759152;37321.81339093287;37465;37484.62515374866;37548.62451375451;15566;15700.842991570167;15728.292226849819;15728.449504267295;15732.92133539325;12224.572139975058;12239.632811015685;12305.692357691129;12401;13049.673758156123;7838.098618658813;7978.880316795253;8180.713675021346;8193.754187374388;8270.545120018456;3385.559877215946;3436.43298855686;3445.3109378124477;3458.1873259784434;3475.756697031234;999.2605471950704;1023.4217667018111;1041.1514615588424;1043.7442826507554;1089.561778452451;23354.883225584104;28822.135335939955;29434;29692.257693557403;30396;1062.6333914799536;1106.435717783936;1165.0388429545658;1231.6612931443924;1288.9033322500663;13600.523981660594;15265.771013434807;15267.541973740805;15364.00133991287;15677;640199;675335.5964743051;676336;676914;677353;58616.413835861946;60239.09641355383;60378.69810650919;60417;60534.69732651309;1352.3103217359217;1388.6806034611893;1424.774693763342;1489.970200595982;1505.503183949316;26244.868775656;29304.413911721218;30449.847750761106;30491.847540762155;31313.21716957003;48974.75512622414;49152.26271605857;49988.75005624948;50001.49998499968;50166.74916625393;552905;556165;556825;557048;557591;23071.769282306963;25636.61545076788;26231;26423.33941651474;26510.602340965288;44991.7750411259;45037.09925801531;45275.09449811051;46277;46700.53299466962;35999;37569.685061022734;37724.81137594295;38238;38323.80838095903;36220;36405.81797090998;36798.264034719694;37288.81355593204;37626;36751.632483674824;37312;37887.43168852525;37892.24215515729;37962.62037379591;31675.049748507583;33036.00891973149;33081.20322553264;33129.50305745367;33279.50080748839;24176.153834615703;24413;24477.755222447548;25098.12156574511;25211.11761088353 12:31:22 INFO - PROCESS | 1660 | |2;jquery.html;386.74281602734186;390.8299889548045;391.4871518311013;397.9621935916087;419.8509529117163;20161.495962600944;20487.975601219954;21413.57172856542;21442.142314307428;21550.81470519121;20418.285360012404;20600.793992060077;20611.969401529903;20862.791372086274;21047.158113675472;5685.317761868571;6006.339302676702;6010.12853136295;6011.218541589609;6013;5823.184754134425;5893.8821223575505;5971.761129554812;5995.13070604762;5998.430149135847;5776.884462310752;5855.443732845394;5919.1713160157615;5932.851678708023;5991.640501569898;5767.509761670263;5856.707164641751;5879.500242479395;5883.61756485827;5960.850978725545;4982.701037937735;5146.099432599318;5174.2238664200295;5185.896282074375;5195.870103247411;25532.617010744852;25786.839592218395;26148.73851261501;26225.868870655715;26479;2281.7718228177214;2542;2571.6271140684685;2855.0836294952533;2894.442670073464;14576.052556583894;14796;14901.403943842233;15054.322555485029;15352.846471535253;1712.9914350428169;1781.6703909776604;1787.3386846866613;1791.4682946081173;1810.149229861976;78455.21544784536;79315;87521;118019;129889.35055324757;11143.498542565603;11251.662450126509;11275.887241127648;11277.718057048476;11402.714932126763;11903.821442678367;13952.651183720489;14020.649483762783;14070.226137562327;14090.295485225903;6518.837029074311;8225.218604232588;8791.912080879109;8825.02924678285;8835.69075082369;7493.512921660074;8962.68630597926;8962.775930601787;9059.864102038475;9142.131497507726;6478.902816457757;6601.570897891622;6608.438282745924;6625.635590042498;6629.1382120325015;1579.6050987253186;1899.6865517189785;1914.8659593828577;1922.7500424944667;1951.4926119208824;897.4839467306325;932.8973812880578;977.9902200977899;1093.4969913839723;1111.188832152517;20267;20274.898625507074;20859.791402086088;20980.685289720666;21019;960.2989817433313;999.6501224571342;1006.5219020964983;1030.469308306223;1070.7644318249972;11042.668719938414;11094.22340436178;11296.491657875415;11297.03975162104;11313.773724525628;532399;543665;581948;620535.8973205105;621039;101281.43903215583;127752;128578;130030.04954925683;137441.3127934374;983.4590974963742;1020.1175982774846;1063.2450959818461;1065.1319174872453;1065.2064212161988;18996.955167465923;19361.903190483958;19412.8058719411;19417.93201373938;19419.2232310706;41296.1740765189;44059.77970110129;48189.27716084332;51003;53469.7326513365;75652.62173689096;75968;77357.4528509438;78331.60834195792;79828.20171798208;7028.297170282931;7513.737019204302;7567.394608431422;7595.3164215220895;7865.292123708894;9002;10839.132869370274;12781.936090319488;13125.01562382806;13157.407916643582;11020.5040773167;11086.22396432242;11220.887791121984;11242.718932026764;11374.658760237206;10583;10738.463076846283;10789.136869050613;11011.44942752843;11060.391678457761;11075;11269.267497612629;11390.544378224778;11445.370504622326;11516.251443656136;6539.836504087436;7403;7483.625818709152;7607.35337496319;7798.6880524780645;10311.329763565343;10770.676879693621;10806.135509159405;11069.114470842153;11103 12:31:22 INFO - PROCESS | 1660 | |3;mootools.html;1409.9577012689622;1628.104542501624;1654.4540301700436;1900.5153685810117;2004.4587961250454;8050.718224862131;8504.872426913586;8516.829663406728;8517.190866867653;8522;8496.83006339873;8859.867101993475;8861.202491775739;8992.595333210005;9006.549672516365;2440.450898547826;2471.715752688437;2471.864047477388;2474.6288056791536;2478.1698131126095;2429.9392515187087;2478.628205769131;2483.677121974141;2484.24230609664;2492.8753562321813;2431.963520547193;2455.1038870812113;2476.578981573137;2482.007197121157;2485.478049609584;2420.5279970405777;2421.830471866961;2464.753524647539;2467.4571594249237;2471.3203868936007;2441.548313561979;2444.4599902061764;2479.1570865905496;2482.981977389262;2489.352768280242;8835.835220370986;8952.731418057469;8955.925288965365;8962.551872406419;8973.641054357819;5334.199870019489;5373;5550;5556.527695145917;5562.3881373048935;3460.117669994135;3613.674769270778;3665.358562251622;3684.9078773030888;3692.464592634053;1050;1057.3338796558196;1058.4813441413653;1077.159815344033;1086.6957251969461;11554;11782.941085294518;11783.764324713458;11974.580889668907;11990.640280791591;4915.287283343895;4934.851954441372;5037.657489267508;5039.3448851649055;5079.314292570456;10272.845907311395;10291.279610427198;10314.071733544017;10314.123299519624;10331.276810623183;5514.476124768141;5586.972065139648;5590.329160500766;5599.132134519156;5604.97197514018;4466;4491.528389519119;4976.925346119811;5017.874553136201;5073.518015788494;4081.6122468365456;4086.4074709167003;4156.43888075112;4204.07510347723;4216.578342165759;1343.4223283987978;1357.9253141077138;1359.198073136854;1361.1628848258447;1361.251311778518;728.970841166358;810.3112354498746;888.8089060851947;947.4504787223394;970.3838062830013;5812.331581868055;5935.673537955454;5947.5539334550085;5972.85067873298;6177.2587289525545;928.9814203716023;962.2206013129388;965.0687086961116;974.8245315843075;987.5802783816849;3811.447340135665;3959.2279505496153;3980.6707335336373;4041.939370909439;4056.8985775355263;10462.372257664565;10738.463076846127;10917.181211409175;10937.617183398543;10945.507452164671;10838.403887786248;10924.453777311102;10924.726881828017;10939.56241750321;10939.617113400993;7945.682172713026;8291.170882911661;8371.455855369382;8378.455400399076;8412.327013839;5762.654240745569;5779.624324418899;5859.121131830259;5926.762929482772;5950.077737950624;10061.748456288651;10224.182065434896;10237.18102551809;10239.692809215736;10243.641472548425;10040.497975101069;10103.54534045955;10119.696409107739;10311.123554497652;10329.483525823829;7565.583892885944;7583;7678.500897441649;7680.769576912701;7680.769576912701;8174.673013079648;8270.545120018456;8289.95855020721;8328.417010809186;8364.87452688198;7466.589337586485;7474.215207403145;7479;7571.772846814604;7787.688492460238;7348.853022939618;7359.153697324876;7409.777706668809;7550.433717471322;7562.810929726801;6997.790066298019;7118.039064726403;7167.9964804928395;7170.498065135599;7173.641317934187;4888.413390393175;4907.77914993819;4916.606671466346;4967.279744436964;5131.307273518177;4993.550580447777;5045.091883461012;5050.292958985819;5073.619478539198;5294.947050529446 12:31:22 INFO - PROCESS | 1660 | |4;prototype.html;309.4260147426524;317.60616835124455;323.69895996723045;324.87654691217335;328.55152716541926;5037.546620804127;5194.766235519402;5338.23838133416;5395.163749618806;5429.864253393667;5411.83764487066;5443.945560544394;5463.945360546393;5465.781368745245;5470.9452905471035;3564.964350356502;3570.517980072696;3957.861474848381;4004.7797371144575;4068.328725760246;3231.850819205226;4094.3858421236755;4102.364133559303;4131.690123240769;4135.565765594614;3167.129039514129;3555.6977656899194;4025.0138716014617;4040.8181631826637;4088.0188754698806;3804.3912973924316;3900.6294402031763;4038.798060096998;4070.7354021988476;4133.731307465006;3310.2386451116136;3530.6116327203986;3553.271579326258;3559.7330200235087;3560.9109772255642;5266.8419947401635;5267.920981185285;5271.65734227274;5288.814891478818;5298.311219541473;3544.521489598897;4038.515378154611;4698.671093023489;4723.267893476516;4740.463851839249;5480.808171713971;5694.402087780765;5719.656820590778;5721.628094173865;5742.196092547047;3474.583050034012;3727.683146932514;3899.98050009751;3902.6682731967817;3942.8619998299923;4876.707397556158;5221.190715439113;5728.82813515591;6001.849953751147;6077.544184186159;3632.7648599476306;3640.672339489459;3810.180811125566;3898.8245528951265;3952.2885880541203;3594.3170797548905;3621.4386770050683;3874.3994680824517;3894.1822217334675;3977.3636218204947;4039.4748682671066;4599.896024953987;4701.083288758659;4710.505396933273;4728;4937.432195297515;5245;5600.60795744294;5667.809759950374;5787.739551720183;1148.5004023249746;1361.6255529729206;1369.9452021919012;1370.465518447806;1379.2207402817376;788;789.8696715041954;811.472542847154;836.9203727191855;848.211163617829;4928.753562321869;5191.532762051434;5192.676686920374;5245.868853278622;5352.437994010573;5463.672179669153;5814.470705243233;5859.334003028612;5885.793997210163;5919.763209471659;4646.512116227815;5016.347874776328;5088.440271570124;5132.230165475134;5421.183903392329;2596.9740302596733;2647.404334024829;2928.4143171365813;2953.291210109558;3127.1712996056062;5336.252924590561;5637.744902039135;5952.672603006789;5959.106134079836;6074.726637301332;5343.047965998791;5523.28197334344;6128.754849805958;6138.846528836815;6142.877142457126;5586.3520261522945;5952.476118150151;6138.600990935575;6148.264022510119;6197.9093798093045;4850.151223535797;5613.859653508695;5761.769529218785;5922.822315330547;5954.315253745702;5017.598592112549;5799.5070419014855;5811.970940145441;5825.563082768725;5842.1820945066875;5647.491725744702;5951.761929522944;6114.6942652868065;6118.143459915526;6124.693765311806;3275.5250488679485;3445.9655403445645;3464.1859163097156;3477.009052420048;3477.8434970425888;4428.446444194476;4447.799849006868;4716.929246061243;4953.504649535018;5018.824341148043;4223.809928553161;4438.647516816092;4446.909952322403;4515.367848501278;4982.451930287738;4587.724736515686;4724.881877953078;4730.810767569259;4804.47764923418;4876.536729010738;4281.743095414286;4645.862903064493;4797.529272424635;4825.914169311876;5143.125668636266;4678.625709943264;4752.669252609275;5546.417626149197;5583.776648933997;5608.551315894801;5103.668261562986;5462.617616766947;5487.829249770123;5487.945120548743;5511.283533140666 12:31:22 INFO - PROCESS | 1660 | |5;yui.html;614.6865098799612;730.7223255163037;745.6197339356926;748.128430378609;788.8934993775842;15160;15720.135392553406;15742.921285393586;15744.921275393635;15819.683606327866;14166.079204851678;14368.928155359208;14421.206833624146;14429.927850360733;14657;904.141065987313;927.1516562345446;928.3269629518612;928.5403725156057;929.4376901974309;904.1365495951351;925.9768505787342;926.2867591954191;926.7775733823901;926.9860952085725;970.6845275285525;971.946542940138;972.8686627305294;973.3770386952356;976.3995142987047;963.889152747436;964.3635200767495;966.7534778631474;967.1295833749647;972.9124378805906;713.6324792731747;714.1822613108014;714.2322003845886;715.0204220218283;715.4240836126916;13316.267605281706;13587.660308492366;13638.590842274747;13646.658833529142;13673.316334183251;9836.704898853046;11197.216194866363;11210.775784484345;11314.547418103266;11745.119116066244;9235.445873247627;9389.76525586859;9516;9593.42439453628;10183.388996660224;3343.7157841583253;3397.69420752131;3458.2564748579175;3460.1003739028033;3512.0517460285837;62426.68786656038;73017.53964920725;73560;82643.347133057;82981.58509207415;9344.439333640003;9433.433993960247;9645.131938125602;9692;9996.800063998679;21638.24266150677;22190.223342182948;22202.66695999561;22215.444613884458;22228.55542889133;7544.773656790305;7563;7585.317321441097;7661.157272699998;7942.3646108311195;3347.3974684557015;4003.9589706676475;4044.3906255450784;4110.383442483591;4393.7363758174615;3419.7948123112196;3493.406120959443;3538.1862171700136;3542.6811586956783;3543.114221444639;618.1315252070848;618.832915112917;630.7161777200279;632.4434497642045;638.0939066525452;471.220130683719;480.7043668144066;510.86206724185007;544.311446020784;565.3272605599406;7153.642317884085;7556.168821429638;7661.655225514865;7697.461177717506;7709.151993280735;491.63864559548705;519.1563708972919;533.5198321510671;548.3502050070679;561.5451484297732;3638.4542318652416;3652.2878038782183;3659.076329386465;3710.6289371062676;3765.4163604641326;79653;87553.54949521145;98189.50905245428;101012.98987010182;101313.97372052666;82226.17773822186;85597;86485;86523;87491;57663.711681441324;73760.26239737641;75234;75470.86793698098;78942;8075.313598344206;8197.79505512367;8201.30288925448;8270.875936860832;8418.284445822173;45620.54379456296;46946.53053469422;47315.76342118267;48235.5176448231;49065.01869962509;63071.42321442001;64396.712065757485;67427;67847.66076169588;68599.65700171467;10958.452077396258;11086.778264434828;11253.88746112561;11424.771504570028;11546.884531154581;11620.709482263012;11629.88370116288;11906.345151016778;12228.144029917821;12267.386630668252;11327.207095503565;11601.477933492844;11616.361100139235;11854.94072529632;11919.821202681793;9463.337566370288;11385.943070284595;11584.536618535165;11657.941710291394;11957.222780519238;11050.557977680803;11105.666829995112;11125.721856953318;11195.496202670736;12177.57378491784;9599.952000239955;9703.320767546204;9716.076972687584;9731.756706082404;9978.60085596568;9437.952810235905;9623.182029527568;9670.85493717609;9688.806223875625;9901.05939935705 12:31:22 INFO - PROCESS | 1660 | __end_tp_report 12:31:22 INFO - PROCESS | 1660 | __start_cc_report 12:31:22 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1705 12:31:22 INFO - PROCESS | 1660 | __end_cc_report 12:31:22 INFO - PROCESS | 1660 | __startTimestamp1463427082731__endTimestamp 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | ------- Summary: start ------- 12:31:22 INFO - PROCESS | 1660 | Number of tests: 6 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5773.18 Median:6100.30 stddev:616.49 (10.1%) stddev-sans-first:591.07 12:31:22 INFO - PROCESS | 1660 | Values: 3460.2 3466.8 3483.5 3484.7 3537.7 5787.9 5790.7 5805.1 5895.3 5899.3 5825.4 5838.2 5885.3 5960.0 5977.1 5117.8 5158.5 5200.4 5202.9 5314.6 5260.4 5288.8 5296.8 5325.4 5325.7 5161.7 5164.6 5183.4 5223.6 5270.4 5190.8 5197.7 5214.4 5290.9 5324.4 5201.1 5202.7 5211.7 5237.3 5286.1 5552.9 5654.8 5803.7 5822.0 5896.9 5274.4 6089.3 6100.3 6110.1 6115.1 6197.0 6200.4 6217.2 6241.9 6242.2 5809.9 5949.6 5996.9 6017.0 6043.4 6189.4 6237.1 6242.8 6325.2 6359.0 6169.2 6175.1 6179.4 6190.2 6194.8 6129.2 6148.2 6155.2 6214.7 6227.2 6111.1 6120.8 6123.5 6131.7 6133.7 6119.2 6125.8 6148.0 6152.9 6154.5 4866.9 4913.5 4944.1 4962.3 4974.5 4403.3 4417.4 4419.6 4440.2 4466.7 5843.2 5876.4 5889.8 5892.8 5897.7 6111.0 6113.7 6140.4 6175.4 6179.4 5770.4 5809.2 5823.8 5832.7 5847.9 5399.2 5399.8 5412.1 5413.5 5455.3 6344.8 6346.2 6356.3 6362.6 6370.4 6112.2 6115.4 6118.7 6191.6 6211.7 6255.1 6322.5 6326.3 6356.0 6406.7 6108.2 6138.7 6147.3 6160.6 6161.9 6112.4 6144.1 6228.4 6228.8 6241.3 5970.1 6098.0 6107.6 6131.5 6141.2 6109.1 6132.7 6143.0 6149.2 6157.9 6099.0 6116.9 6130.8 6132.7 6144.4 6118.1 6119.8 6125.9 6130.3 6138.0 5975.3 6064.8 6132.0 6148.6 6155.2 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:69283.59 Median:3451.75 stddev:154929.04 (4488.4%) stddev-sans-first:155277.57 12:31:22 INFO - PROCESS | 1660 | Values: 497.3 501.7 534.2 559.5 563.2 31346.7 41609.6 42628.8 42781.9 44636.8 43713.9 43861.8 43899.0 43910.0 43919.0 8619.8 9661.9 9826.4 9826.5 9840.7 8685.8 10023.8 11804.8 11825.5 11917.3 11781.8 11803.9 11857.9 11875.8 11878.8 8690.0 11601.2 11704.8 11789.8 11826.6 11687.0 11801.9 11802.3 11804.8 11813.6 39654.0 39945.6 39975.4 40021.8 40261.6 3158.7 3270.2 3392.5 3435.5 3470.8 28046.7 28369.4 28654.3 28947.6 29292.6 2698.4 2703.2 2719.0 2754.8 2818.5 480205.0 499964.0 500377.0 501080.0 501308.0 30083.7 58675.4 59603.7 59607.0 59763.7 36146.1 37321.8 37465.0 37484.6 37548.6 15566.0 15700.8 15728.3 15728.4 15732.9 12224.6 12239.6 12305.7 12401.0 13049.7 7838.1 7978.9 8180.7 8193.8 8270.5 3385.6 3436.4 3445.3 3458.2 3475.8 999.3 1023.4 1041.2 1043.7 1089.6 23354.9 28822.1 29434.0 29692.3 30396.0 1062.6 1106.4 1165.0 1231.7 1288.9 13600.5 15265.8 15267.5 15364.0 15677.0 640199.0 675335.6 676336.0 676914.0 677353.0 58616.4 60239.1 60378.7 60417.0 60534.7 1352.3 1388.7 1424.8 1490.0 1505.5 26244.9 29304.4 30449.8 30491.8 31313.2 48974.8 49152.3 49988.8 50001.5 50166.7 552905.0 556165.0 556825.0 557048.0 557591.0 23071.8 25636.6 26231.0 26423.3 26510.6 44991.8 45037.1 45275.1 46277.0 46700.5 35999.0 37569.7 37724.8 38238.0 38323.8 36220.0 36405.8 36798.3 37288.8 37626.0 36751.6 37312.0 37887.4 37892.2 37962.6 31675.0 33036.0 33081.2 33129.5 33279.5 24176.2 24413.0 24477.8 25098.1 25211.1 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:33896.40 Median:2411.89 stddev:96690.31 (4008.9%) stddev-sans-first:96928.82 12:31:22 INFO - PROCESS | 1660 | Values: 386.7 390.8 391.5 398.0 419.9 20161.5 20488.0 21413.6 21442.1 21550.8 20418.3 20600.8 20612.0 20862.8 21047.2 5685.3 6006.3 6010.1 6011.2 6013.0 5823.2 5893.9 5971.8 5995.1 5998.4 5776.9 5855.4 5919.2 5932.9 5991.6 5767.5 5856.7 5879.5 5883.6 5960.9 4982.7 5146.1 5174.2 5185.9 5195.9 25532.6 25786.8 26148.7 26225.9 26479.0 2281.8 2542.0 2571.6 2855.1 2894.4 14576.1 14796.0 14901.4 15054.3 15352.8 1713.0 1781.7 1787.3 1791.5 1810.1 78455.2 79315.0 87521.0 118019.0 129889.4 11143.5 11251.7 11275.9 11277.7 11402.7 11903.8 13952.7 14020.6 14070.2 14090.3 6518.8 8225.2 8791.9 8825.0 8835.7 7493.5 8962.7 8962.8 9059.9 9142.1 6478.9 6601.6 6608.4 6625.6 6629.1 1579.6 1899.7 1914.9 1922.8 1951.5 897.5 932.9 978.0 1093.5 1111.2 20267.0 20274.9 20859.8 20980.7 21019.0 960.3 999.7 1006.5 1030.5 1070.8 11042.7 11094.2 11296.5 11297.0 11313.8 532399.0 543665.0 581948.0 620535.9 621039.0 101281.4 127752.0 128578.0 130030.0 137441.3 983.5 1020.1 1063.2 1065.1 1065.2 18997.0 19361.9 19412.8 19417.9 19419.2 41296.2 44059.8 48189.3 51003.0 53469.7 75652.6 75968.0 77357.5 78331.6 79828.2 7028.3 7513.7 7567.4 7595.3 7865.3 9002.0 10839.1 12781.9 13125.0 13157.4 11020.5 11086.2 11220.9 11242.7 11374.7 10583.0 10738.5 10789.1 11011.4 11060.4 11075.0 11269.3 11390.5 11445.4 11516.3 6539.8 7403.0 7483.6 7607.4 7798.7 10311.3 10770.7 10806.1 11069.1 11103.0 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5820.31 Median:4925.73 stddev:3222.63 (65.4%) stddev-sans-first:3214.62 12:31:22 INFO - PROCESS | 1660 | Values: 1410.0 1628.1 1654.5 1900.5 2004.5 8050.7 8504.9 8516.8 8517.2 8522.0 8496.8 8859.9 8861.2 8992.6 9006.5 2440.5 2471.7 2471.9 2474.6 2478.2 2429.9 2478.6 2483.7 2484.2 2492.9 2432.0 2455.1 2476.6 2482.0 2485.5 2420.5 2421.8 2464.8 2467.5 2471.3 2441.5 2444.5 2479.2 2483.0 2489.4 8835.8 8952.7 8955.9 8962.6 8973.6 5334.2 5373.0 5550.0 5556.5 5562.4 3460.1 3613.7 3665.4 3684.9 3692.5 1050.0 1057.3 1058.5 1077.2 1086.7 11554.0 11782.9 11783.8 11974.6 11990.6 4915.3 4934.9 5037.7 5039.3 5079.3 10272.8 10291.3 10314.1 10314.1 10331.3 5514.5 5587.0 5590.3 5599.1 5605.0 4466.0 4491.5 4976.9 5017.9 5073.5 4081.6 4086.4 4156.4 4204.1 4216.6 1343.4 1357.9 1359.2 1361.2 1361.3 729.0 810.3 888.8 947.5 970.4 5812.3 5935.7 5947.6 5972.9 6177.3 929.0 962.2 965.1 974.8 987.6 3811.4 3959.2 3980.7 4041.9 4056.9 10462.4 10738.5 10917.2 10937.6 10945.5 10838.4 10924.5 10924.7 10939.6 10939.6 7945.7 8291.2 8371.5 8378.5 8412.3 5762.7 5779.6 5859.1 5926.8 5950.1 10061.7 10224.2 10237.2 10239.7 10243.6 10040.5 10103.5 10119.7 10311.1 10329.5 7565.6 7583.0 7678.5 7680.8 7680.8 8174.7 8270.5 8290.0 8328.4 8364.9 7466.6 7474.2 7479.0 7571.8 7787.7 7348.9 7359.2 7409.8 7550.4 7562.8 6997.8 7118.0 7168.0 7170.5 7173.6 4888.4 4907.8 4916.6 4967.3 5131.3 4993.6 5045.1 5050.3 5073.6 5294.9 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4474.52 Median:4902.73 stddev:1411.60 (28.8%) stddev-sans-first:1380.51 12:31:22 INFO - PROCESS | 1660 | Values: 309.4 317.6 323.7 324.9 328.6 5037.5 5194.8 5338.2 5395.2 5429.9 5411.8 5443.9 5463.9 5465.8 5470.9 3565.0 3570.5 3957.9 4004.8 4068.3 3231.9 4094.4 4102.4 4131.7 4135.6 3167.1 3555.7 4025.0 4040.8 4088.0 3804.4 3900.6 4038.8 4070.7 4133.7 3310.2 3530.6 3553.3 3559.7 3560.9 5266.8 5267.9 5271.7 5288.8 5298.3 3544.5 4038.5 4698.7 4723.3 4740.5 5480.8 5694.4 5719.7 5721.6 5742.2 3474.6 3727.7 3900.0 3902.7 3942.9 4876.7 5221.2 5728.8 6001.8 6077.5 3632.8 3640.7 3810.2 3898.8 3952.3 3594.3 3621.4 3874.4 3894.2 3977.4 4039.5 4599.9 4701.1 4710.5 4728.0 4937.4 5245.0 5600.6 5667.8 5787.7 1148.5 1361.6 1369.9 1370.5 1379.2 788.0 789.9 811.5 836.9 848.2 4928.8 5191.5 5192.7 5245.9 5352.4 5463.7 5814.5 5859.3 5885.8 5919.8 4646.5 5016.3 5088.4 5132.2 5421.2 2597.0 2647.4 2928.4 2953.3 3127.2 5336.3 5637.7 5952.7 5959.1 6074.7 5343.0 5523.3 6128.8 6138.8 6142.9 5586.4 5952.5 6138.6 6148.3 6197.9 4850.2 5613.9 5761.8 5922.8 5954.3 5017.6 5799.5 5812.0 5825.6 5842.2 5647.5 5951.8 6114.7 6118.1 6124.7 3275.5 3446.0 3464.2 3477.0 3477.8 4428.4 4447.8 4716.9 4953.5 5018.8 4223.8 4438.6 4446.9 4515.4 4982.5 4587.7 4724.9 4730.8 4804.5 4876.5 4281.7 4645.9 4797.5 4825.9 5143.1 4678.6 4752.7 5546.4 5583.8 5608.6 5103.7 5462.6 5487.8 5487.9 5511.3 12:31:22 INFO - PROCESS | 1660 | 12:31:22 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18271.88 Median:31528.70 stddev:26031.60 (82.6%) stddev-sans-first:26070.86 12:31:22 INFO - PROCESS | 1660 | Values: 614.7 730.7 745.6 748.1 788.9 15160.0 15720.1 15742.9 15744.9 15819.7 14166.1 14368.9 14421.2 14429.9 14657.0 904.1 927.2 928.3 928.5 929.4 904.1 926.0 926.3 926.8 927.0 970.7 971.9 972.9 973.4 976.4 963.9 964.4 966.8 967.1 972.9 713.6 714.2 714.2 715.0 715.4 13316.3 13587.7 13638.6 13646.7 13673.3 9836.7 11197.2 11210.8 11314.5 11745.1 9235.4 9389.8 9516.0 9593.4 10183.4 3343.7 3397.7 3458.3 3460.1 3512.1 62426.7 73017.5 73560.0 82643.3 82981.6 9344.4 9433.4 9645.1 9692.0 9996.8 21638.2 22190.2 22202.7 22215.4 22228.6 7544.8 7563.0 7585.3 7661.2 7942.4 3347.4 4004.0 4044.4 4110.4 4393.7 3419.8 3493.4 3538.2 3542.7 3543.1 618.1 618.8 630.7 632.4 638.1 471.2 480.7 510.9 544.3 565.3 7153.6 7556.2 7661.7 7697.5 7709.2 491.6 519.2 533.5 548.4 561.5 3638.5 3652.3 3659.1 3710.6 3765.4 79653.0 87553.5 98189.5 101013.0 101314.0 82226.2 85597.0 86485.0 86523.0 87491.0 57663.7 73760.3 75234.0 75470.9 78942.0 8075.3 8197.8 8201.3 8270.9 8418.3 45620.5 46946.5 47315.8 48235.5 49065.0 63071.4 64396.7 67427.0 67847.7 68599.7 10958.5 11086.8 11253.9 11424.8 11546.9 11620.7 11629.9 11906.3 12228.1 12267.4 11327.2 11601.5 11616.4 11854.9 11919.8 9463.3 11385.9 11584.5 11657.9 11957.2 11050.6 11105.7 11125.7 11195.5 12177.6 9600.0 9703.3 9716.1 9731.8 9978.6 9438.0 9623.2 9670.9 9688.8 9901.1 12:31:22 INFO - PROCESS | 1660 | -------- Summary: end -------- 12:31:22 INFO - PROCESS | 1660 | 12:31:23 INFO - TEST-INFO | 1660: exit 0 12:31:23 INFO - TEST-OK | dromaeo_css | took 1116408ms 12:31:23 INFO - TEST-START | kraken 12:31:23 INFO - operating with platform_type : mac_ 12:31:23 INFO - Initialising browser for kraken test... 12:31:23 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpov5fdC/profile http://localhost:49275/getInfo.html) 12:31:25 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 12:31:25 INFO - PROCESS | 1668 | colorDepth:24 12:31:25 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 12:31:25 INFO - PROCESS | 1668 | __metrics 12:31:25 INFO - PROCESS | 1668 | 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" 12:31:25 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:31:26 INFO - TEST-INFO | 1668: exit 0 12:31:26 INFO - Browser initialized. 12:31:26 INFO - Running cycle 1/1 for kraken test... 12:31:26 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpov5fdC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:38 INFO - PROCESS | 1670 | RSS: Main: 239259648 12:31:38 INFO - PROCESS | 1670 | 12:31:41 INFO - PROCESS | 1670 | 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) 12:31:41 INFO - PROCESS | 1670 | RSS: Main: 287526912 12:31:41 INFO - PROCESS | 1670 | 12:31:44 INFO - PROCESS | 1670 | 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) 12:31:45 INFO - PROCESS | 1670 | RSS: Main: 296611840 12:31:45 INFO - PROCESS | 1670 | 12:31:48 INFO - PROCESS | 1670 | 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) 12:31:49 INFO - PROCESS | 1670 | RSS: Main: 382009344 12:31:49 INFO - PROCESS | 1670 | 12:31:51 INFO - PROCESS | 1670 | 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) 12:31:52 INFO - PROCESS | 1670 | RSS: Main: 284852224 12:31:52 INFO - PROCESS | 1670 | 12:31:54 INFO - PROCESS | 1670 | 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) 12:31:55 INFO - PROCESS | 1670 | RSS: Main: 288616448 12:31:55 INFO - PROCESS | 1670 | 12:31:58 INFO - PROCESS | 1670 | 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) 12:31:58 INFO - PROCESS | 1670 | RSS: Main: 325173248 12:31:58 INFO - PROCESS | 1670 | 12:32:01 INFO - PROCESS | 1670 | 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) 12:32:02 INFO - PROCESS | 1670 | RSS: Main: 329605120 12:32:02 INFO - PROCESS | 1670 | 12:32:05 INFO - PROCESS | 1670 | 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) 12:32:06 INFO - PROCESS | 1670 | RSS: Main: 329027584 12:32:06 INFO - PROCESS | 1670 | 12:32:07 INFO - PROCESS | 1670 | 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) 12:32:08 INFO - PROCESS | 1670 | RSS: Main: 316731392 12:32:08 INFO - PROCESS | 1670 | 12:32:10 INFO - PROCESS | 1670 | 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) 12:32:11 INFO - PROCESS | 1670 | RSS: Main: 324063232 12:32:11 INFO - PROCESS | 1670 | 12:32:13 INFO - PROCESS | 1670 | 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) 12:32:14 INFO - PROCESS | 1670 | RSS: Main: 325287936 12:32:14 INFO - PROCESS | 1670 | 12:32:17 INFO - PROCESS | 1670 | 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) 12:32:17 INFO - PROCESS | 1670 | RSS: Main: 329875456 12:32:17 INFO - PROCESS | 1670 | 12:32:20 INFO - PROCESS | 1670 | 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) 12:32:21 INFO - PROCESS | 1670 | RSS: Main: 327634944 12:32:21 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | 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) 12:32:23 INFO - PROCESS | 1670 | RSS: Main: 374169600 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | __start_tp_report 12:32:23 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 12:32:23 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 12:32:23 INFO - PROCESS | 1670 | |i|pagename|runs| 12:32:23 INFO - PROCESS | 1670 | |0;ai-astar;111;105;113;112;112;113;112;113;113;112 12:32:23 INFO - PROCESS | 1670 | |1;audio-beat-detection;151;124;138;201;131;160;153;117;165;119 12:32:23 INFO - PROCESS | 1670 | |2;audio-dft;184;186;185;187;183;182;184;183;182;183 12:32:23 INFO - PROCESS | 1670 | |3;audio-fft;80;75;57;126;57;61;56;75;132;77 12:32:23 INFO - PROCESS | 1670 | |4;audio-oscillator;90;91;134;79;80;77;102;81;85;101 12:32:23 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;95;91;92;91;92;91;91;87;94;89 12:32:23 INFO - PROCESS | 1670 | |6;imaging-darkroom;116;99;142;89;96;95;95;97;103;117 12:32:23 INFO - PROCESS | 1670 | |7;imaging-desaturate;87;86;86;84;85;83;86;84;84;83 12:32:23 INFO - PROCESS | 1670 | |8;json-parse-financial;57;57;59;58;56;58;58;122;58;56 12:32:23 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;62;68;70;74;70;63;70;71;67;66 12:32:23 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;84;83;86;74;97;103;94;84;86;87 12:32:23 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;148;147;147;142;141;142;135;134;215;140 12:32:23 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;163;155;162;163;162;162;162;158;164;160 12:32:23 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;64;66;65;66;70;73;79;80;76;63 12:32:23 INFO - PROCESS | 1670 | __end_tp_report 12:32:23 INFO - PROCESS | 1670 | __start_cc_report 12:32:23 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2277 12:32:23 INFO - PROCESS | 1670 | __end_cc_report 12:32:23 INFO - PROCESS | 1670 | __startTimestamp1463427143257__endTimestamp 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | ------- Summary: start ------- 12:32:23 INFO - PROCESS | 1670 | Number of tests: 14 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:2.41 (2.1%) stddev-sans-first:2.55 12:32:23 INFO - PROCESS | 1670 | Values: 111.0 105.0 113.0 112.0 112.0 113.0 112.0 113.0 113.0 112.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:152.00 stddev:25.82 (17.0%) stddev-sans-first:27.32 12:32:23 INFO - PROCESS | 1670 | Values: 151.0 124.0 138.0 201.0 131.0 160.0 153.0 117.0 165.0 119.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.66 (0.9%) stddev-sans-first:1.76 12:32:23 INFO - PROCESS | 1670 | Values: 184.0 186.0 185.0 187.0 183.0 182.0 184.0 183.0 182.0 183.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:79.60 Median:68.00 stddev:27.63 (40.6%) stddev-sans-first:29.31 12:32:23 INFO - PROCESS | 1670 | Values: 80.0 75.0 57.0 126.0 57.0 61.0 56.0 75.0 132.0 77.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:80.50 stddev:17.19 (21.3%) stddev-sans-first:18.21 12:32:23 INFO - PROCESS | 1670 | Values: 90.0 91.0 134.0 79.0 80.0 77.0 102.0 81.0 85.0 101.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:91.50 stddev:2.26 (2.5%) stddev-sans-first:1.96 12:32:23 INFO - PROCESS | 1670 | Values: 95.0 91.0 92.0 91.0 92.0 91.0 91.0 87.0 94.0 89.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:95.00 stddev:15.90 (16.7%) stddev-sans-first:16.35 12:32:23 INFO - PROCESS | 1670 | Values: 116.0 99.0 142.0 89.0 96.0 95.0 95.0 97.0 103.0 117.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.24 12:32:23 INFO - PROCESS | 1670 | Values: 87.0 86.0 86.0 84.0 85.0 83.0 86.0 84.0 84.0 83.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:58.00 stddev:20.44 (35.2%) stddev-sans-first:21.52 12:32:23 INFO - PROCESS | 1670 | Values: 57.0 57.0 59.0 58.0 56.0 58.0 58.0 122.0 58.0 56.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:70.00 stddev:3.70 (5.3%) stddev-sans-first:3.19 12:32:23 INFO - PROCESS | 1670 | Values: 62.0 68.0 70.0 74.0 70.0 63.0 70.0 71.0 67.0 66.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.00 stddev:8.19 (9.5%) stddev-sans-first:8.57 12:32:23 INFO - PROCESS | 1670 | Values: 84.0 83.0 86.0 74.0 97.0 103.0 94.0 84.0 86.0 87.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:144.50 stddev:23.64 (16.4%) stddev-sans-first:25.07 12:32:23 INFO - PROCESS | 1670 | Values: 148.0 147.0 147.0 142.0 141.0 142.0 135.0 134.0 215.0 140.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.10 Median:162.00 stddev:2.73 (1.7%) stddev-sans-first:2.80 12:32:23 INFO - PROCESS | 1670 | Values: 163.0 155.0 162.0 163.0 162.0 162.0 162.0 158.0 164.0 160.0 12:32:23 INFO - PROCESS | 1670 | 12:32:23 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:71.50 stddev:6.39 (8.9%) stddev-sans-first:6.37 12:32:23 INFO - PROCESS | 1670 | Values: 64.0 66.0 65.0 66.0 70.0 73.0 79.0 80.0 76.0 63.0 12:32:23 INFO - PROCESS | 1670 | -------- Summary: end -------- 12:32:23 INFO - PROCESS | 1670 | 12:32:24 INFO - TEST-INFO | 1670: exit 0 12:32:24 INFO - TEST-OK | kraken | took 60742ms 12:32:24 INFO - SUITE-END | took 1177s 12:32:24 INFO - Completed test suite (00:19:37) 12:32:24 INFO - javascript benchmark 12:32:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3460.15226269564, 3466.809325487098, 3483.49489324048, 3484.7038001769847, 3537.6910543099057, 5787.855303617412, 5790.739416726247, 5805.129230615405, 5895.292564892214, 5899.321578018524, 5825.388334224898, 5838.21184140142, 5885.264341957255, 5959.970200149004, 5977.1034344848185, 5117.769700363474, 5158.509941555546, 5200.401953775307, 5202.8699282518055, 5314.627976041678, 5260.395054568716, 5288.814891478799, 5296.841094767144, 5325.387580428262, 5325.653832500894, 5161.664491808039, 5164.561012313958, 5183.403908550527, 5223.6343455958095, 5270.446603106676, 5190.844274671766, 5197.7401129943355, 5214.374275086976, 5290.867728306785, 5324.440933701944, 5201.115810312256, 5202.687838729688, 5211.687298762048, 5237.292965449654, 5286.1013627683005, 5552.888942221133, 5654.830355089354, 5803.651780893118, 5821.970890145564, 5896.882062358772, 5274.4198138204765, 6089.317984404817, 6100.298465676459, 6110.144579758838, 6115.113308570232, 6197.03945888388, 6200.410960958747, 6217.222847144107, 6241.906371404432, 6242.15730876335, 5809.912851307234, 5949.583529152961, 5996.914962432213, 6017.037274036134, 6043.365446628129, 6189.442950134509, 6237.126802247689, 6242.812715618538, 6325.240971083499, 6359.046143078575, 6169.167162433104, 6175.135481032659, 6179.3820617937845, 6190.164327815777, 6194.783182588588, 6129.233845769279, 6148.247749391402, 6155.230596175478, 6214.658193799384, 6227.19046523949, 6111.13596929516, 6120.816375508742, 6123.465646421124, 6131.693415329216, 6133.73253493014, 6119.2350956130485, 6125.84685382869, 6147.969260153671, 6152.938470615237, 6154.5076393888385, 4866.902661946812, 4913.533214344631, 4944.085344211332, 4962.255661650781, 4974.527419895104, 4403.3174857897075, 4417.3815665806815, 4419.602235798793, 4440.22296098188, 4466.665000125004, 5843.18195452637, 5876.353601103875, 5889.764409423575, 5892.823215303548, 5897.734601942923, 6111.0, 6113.724882380304, 6140.4473597376455, 6175.44421002112, 6179.443850053517, 5770.394108618535, 5809.186713860063, 5823.767049317979, 5832.650040997553, 5847.853803654858, 5399.244105825188, 5399.784008639689, 5412.079946409041, 5413.485718856702, 5455.263539422207, 6344.809655710336, 6346.238451385863, 6356.269029061532, 6362.618242905441, 6370.394812492806, 6112.17485639433, 6115.419035191828, 6118.710550942667, 6191.566590338633, 6211.720472578654, 6255.124282600348, 6322.525810564134, 6326.304106548184, 6355.968220159055, 6406.743730250924, 6108.175396321618, 6138.693065346625, 6147.262328520606, 6160.630362178284, 6161.907571386343, 6112.449879510866, 6144.078388241799, 6228.439440450381, 6228.750849966132, 6241.251049874044, 5970.0746384310505, 6098.0086717533295, 6107.61364822091, 6131.509479241739, 6141.232345956755, 6109.083637454418, 6132.693365331626, 6142.959235178615, 6149.200603921461, 6157.876842463215, 6099.037411928083, 6116.901675877084, 6130.795626845537, 6132.693365331805, 6144.357926091645, 6118.071005858243, 6119.84700382494, 6125.928510892795, 6130.291143915101, 6137.987232106831, 5975.312839023568, 6064.787732429344, 6131.988221943329, 6148.647297594522, 6155.199824022849], "value": 5734.84462653287, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.2615665736382, 501.7215445427788, 534.2413772443131, 559.5216090242842, 563.2030676592622, 31346.68653313469, 41609.58390416099, 42628.786856065715, 42781.93045173872, 44636.776816115955, 43713.90715232121, 43861.78069109658, 43899.0, 43910.0, 43919.0, 8619.784505387353, 9661.903380966205, 9826.4104153751, 9826.459544725038, 9840.655577054804, 8685.826283474327, 10023.799524009515, 11804.822927656092, 11825.462689850307, 11917.344546049964, 11781.823272650918, 11803.940980295087, 11857.881421185764, 11875.7624847503, 11878.7624247515, 8690.0, 11601.245919015237, 11704.7659046819, 11789.7642047159, 11826.586069487612, 11687.0, 11801.937825595653, 11802.291862488279, 11804.763904721858, 11813.586524471688, 39654.0, 39945.60054399477, 39975.40036899449, 40021.79989100065, 40261.59738402608, 3158.7473002159772, 3270.1661076425357, 3392.4911263310455, 3435.4503279475416, 3470.7803309233354, 28046.71953280482, 28369.432611347656, 28654.283642908886, 28947.56578651322, 29292.560611590845, 2698.3658840172543, 2703.202555246207, 2719.0, 2754.7520723135012, 2818.549032154866, 480205.0, 499964.0, 500377.0, 501080.0, 501308.0, 30083.69916300853, 58675.41324586785, 59603.70198149068, 59607.0, 59763.70118149468, 36146.09634759152, 37321.81339093287, 37465.0, 37484.62515374866, 37548.62451375451, 15566.0, 15700.842991570167, 15728.292226849819, 15728.449504267295, 15732.92133539325, 12224.572139975058, 12239.632811015685, 12305.692357691129, 12401.0, 13049.673758156123, 7838.098618658813, 7978.880316795253, 8180.713675021346, 8193.754187374388, 8270.545120018456, 3385.559877215946, 3436.43298855686, 3445.3109378124477, 3458.1873259784434, 3475.756697031234, 999.2605471950704, 1023.4217667018111, 1041.1514615588424, 1043.7442826507554, 1089.561778452451, 23354.883225584104, 28822.135335939955, 29434.0, 29692.257693557403, 30396.0, 1062.6333914799536, 1106.435717783936, 1165.0388429545658, 1231.6612931443924, 1288.9033322500663, 13600.523981660594, 15265.771013434807, 15267.541973740805, 15364.00133991287, 15677.0, 640199.0, 675335.5964743051, 676336.0, 676914.0, 677353.0, 58616.413835861946, 60239.09641355383, 60378.69810650919, 60417.0, 60534.69732651309, 1352.3103217359217, 1388.6806034611893, 1424.774693763342, 1489.970200595982, 1505.503183949316, 26244.868775656, 29304.413911721218, 30449.847750761106, 30491.847540762155, 31313.21716957003, 48974.75512622414, 49152.26271605857, 49988.75005624948, 50001.49998499968, 50166.74916625393, 552905.0, 556165.0, 556825.0, 557048.0, 557591.0, 23071.769282306963, 25636.61545076788, 26231.0, 26423.33941651474, 26510.602340965288, 44991.7750411259, 45037.09925801531, 45275.09449811051, 46277.0, 46700.53299466962, 35999.0, 37569.685061022734, 37724.81137594295, 38238.0, 38323.80838095903, 36220.0, 36405.81797090998, 36798.264034719694, 37288.81355593204, 37626.0, 36751.632483674824, 37312.0, 37887.43168852525, 37892.24215515729, 37962.62037379591, 31675.049748507583, 33036.00891973149, 33081.20322553264, 33129.50305745367, 33279.50080748839, 24176.153834615703, 24413.0, 24477.755222447548, 25098.12156574511, 25211.11761088353], "value": 18975.382367779825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.74281602734186, 390.8299889548045, 391.4871518311013, 397.9621935916087, 419.8509529117163, 20161.495962600944, 20487.975601219954, 21413.57172856542, 21442.142314307428, 21550.81470519121, 20418.285360012404, 20600.793992060077, 20611.969401529903, 20862.791372086274, 21047.158113675472, 5685.317761868571, 6006.339302676702, 6010.12853136295, 6011.218541589609, 6013.0, 5823.184754134425, 5893.8821223575505, 5971.761129554812, 5995.13070604762, 5998.430149135847, 5776.884462310752, 5855.443732845394, 5919.1713160157615, 5932.851678708023, 5991.640501569898, 5767.509761670263, 5856.707164641751, 5879.500242479395, 5883.61756485827, 5960.850978725545, 4982.701037937735, 5146.099432599318, 5174.2238664200295, 5185.896282074375, 5195.870103247411, 25532.617010744852, 25786.839592218395, 26148.73851261501, 26225.868870655715, 26479.0, 2281.7718228177214, 2542.0, 2571.6271140684685, 2855.0836294952533, 2894.442670073464, 14576.052556583894, 14796.0, 14901.403943842233, 15054.322555485029, 15352.846471535253, 1712.9914350428169, 1781.6703909776604, 1787.3386846866613, 1791.4682946081173, 1810.149229861976, 78455.21544784536, 79315.0, 87521.0, 118019.0, 129889.35055324757, 11143.498542565603, 11251.662450126509, 11275.887241127648, 11277.718057048476, 11402.714932126763, 11903.821442678367, 13952.651183720489, 14020.649483762783, 14070.226137562327, 14090.295485225903, 6518.837029074311, 8225.218604232588, 8791.912080879109, 8825.02924678285, 8835.69075082369, 7493.512921660074, 8962.68630597926, 8962.775930601787, 9059.864102038475, 9142.131497507726, 6478.902816457757, 6601.570897891622, 6608.438282745924, 6625.635590042498, 6629.1382120325015, 1579.6050987253186, 1899.6865517189785, 1914.8659593828577, 1922.7500424944667, 1951.4926119208824, 897.4839467306325, 932.8973812880578, 977.9902200977899, 1093.4969913839723, 1111.188832152517, 20267.0, 20274.898625507074, 20859.791402086088, 20980.685289720666, 21019.0, 960.2989817433313, 999.6501224571342, 1006.5219020964983, 1030.469308306223, 1070.7644318249972, 11042.668719938414, 11094.22340436178, 11296.491657875415, 11297.03975162104, 11313.773724525628, 532399.0, 543665.0, 581948.0, 620535.8973205105, 621039.0, 101281.43903215583, 127752.0, 128578.0, 130030.04954925683, 137441.3127934374, 983.4590974963742, 1020.1175982774846, 1063.2450959818461, 1065.1319174872453, 1065.2064212161988, 18996.955167465923, 19361.903190483958, 19412.8058719411, 19417.93201373938, 19419.2232310706, 41296.1740765189, 44059.77970110129, 48189.27716084332, 51003.0, 53469.7326513365, 75652.62173689096, 75968.0, 77357.4528509438, 78331.60834195792, 79828.20171798208, 7028.297170282931, 7513.737019204302, 7567.394608431422, 7595.3164215220895, 7865.292123708894, 9002.0, 10839.132869370274, 12781.936090319488, 13125.01562382806, 13157.407916643582, 11020.5040773167, 11086.22396432242, 11220.887791121984, 11242.718932026764, 11374.658760237206, 10583.0, 10738.463076846283, 10789.136869050613, 11011.44942752843, 11060.391678457761, 11075.0, 11269.267497612629, 11390.544378224778, 11445.370504622326, 11516.251443656136, 6539.836504087436, 7403.0, 7483.625818709152, 7607.35337496319, 7798.6880524780645, 10311.329763565343, 10770.676879693621, 10806.135509159405, 11069.114470842153, 11103.0], "value": 9690.548183630366, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.9577012689622, 1628.104542501624, 1654.4540301700436, 1900.5153685810117, 2004.4587961250454, 8050.718224862131, 8504.872426913586, 8516.829663406728, 8517.190866867653, 8522.0, 8496.83006339873, 8859.867101993475, 8861.202491775739, 8992.595333210005, 9006.549672516365, 2440.450898547826, 2471.715752688437, 2471.864047477388, 2474.6288056791536, 2478.1698131126095, 2429.9392515187087, 2478.628205769131, 2483.677121974141, 2484.24230609664, 2492.8753562321813, 2431.963520547193, 2455.1038870812113, 2476.578981573137, 2482.007197121157, 2485.478049609584, 2420.5279970405777, 2421.830471866961, 2464.753524647539, 2467.4571594249237, 2471.3203868936007, 2441.548313561979, 2444.4599902061764, 2479.1570865905496, 2482.981977389262, 2489.352768280242, 8835.835220370986, 8952.731418057469, 8955.925288965365, 8962.551872406419, 8973.641054357819, 5334.199870019489, 5373.0, 5550.0, 5556.527695145917, 5562.3881373048935, 3460.117669994135, 3613.674769270778, 3665.358562251622, 3684.9078773030888, 3692.464592634053, 1050.0, 1057.3338796558196, 1058.4813441413653, 1077.159815344033, 1086.6957251969461, 11554.0, 11782.941085294518, 11783.764324713458, 11974.580889668907, 11990.640280791591, 4915.287283343895, 4934.851954441372, 5037.657489267508, 5039.3448851649055, 5079.314292570456, 10272.845907311395, 10291.279610427198, 10314.071733544017, 10314.123299519624, 10331.276810623183, 5514.476124768141, 5586.972065139648, 5590.329160500766, 5599.132134519156, 5604.97197514018, 4466.0, 4491.528389519119, 4976.925346119811, 5017.874553136201, 5073.518015788494, 4081.6122468365456, 4086.4074709167003, 4156.43888075112, 4204.07510347723, 4216.578342165759, 1343.4223283987978, 1357.9253141077138, 1359.198073136854, 1361.1628848258447, 1361.251311778518, 728.970841166358, 810.3112354498746, 888.8089060851947, 947.4504787223394, 970.3838062830013, 5812.331581868055, 5935.673537955454, 5947.5539334550085, 5972.85067873298, 6177.2587289525545, 928.9814203716023, 962.2206013129388, 965.0687086961116, 974.8245315843075, 987.5802783816849, 3811.447340135665, 3959.2279505496153, 3980.6707335336373, 4041.939370909439, 4056.8985775355263, 10462.372257664565, 10738.463076846127, 10917.181211409175, 10937.617183398543, 10945.507452164671, 10838.403887786248, 10924.453777311102, 10924.726881828017, 10939.56241750321, 10939.617113400993, 7945.682172713026, 8291.170882911661, 8371.455855369382, 8378.455400399076, 8412.327013839, 5762.654240745569, 5779.624324418899, 5859.121131830259, 5926.762929482772, 5950.077737950624, 10061.748456288651, 10224.182065434896, 10237.18102551809, 10239.692809215736, 10243.641472548425, 10040.497975101069, 10103.54534045955, 10119.696409107739, 10311.123554497652, 10329.483525823829, 7565.583892885944, 7583.0, 7678.500897441649, 7680.769576912701, 7680.769576912701, 8174.673013079648, 8270.545120018456, 8289.95855020721, 8328.417010809186, 8364.87452688198, 7466.589337586485, 7474.215207403145, 7479.0, 7571.772846814604, 7787.688492460238, 7348.853022939618, 7359.153697324876, 7409.777706668809, 7550.433717471322, 7562.810929726801, 6997.790066298019, 7118.039064726403, 7167.9964804928395, 7170.498065135599, 7173.641317934187, 4888.413390393175, 4907.77914993819, 4916.606671466346, 4967.279744436964, 5131.307273518177, 4993.550580447777, 5045.091883461012, 5050.292958985819, 5073.619478539198, 5294.947050529446], "value": 4696.937227278453, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.4260147426524, 317.60616835124455, 323.69895996723045, 324.87654691217335, 328.55152716541926, 5037.546620804127, 5194.766235519402, 5338.23838133416, 5395.163749618806, 5429.864253393667, 5411.83764487066, 5443.945560544394, 5463.945360546393, 5465.781368745245, 5470.9452905471035, 3564.964350356502, 3570.517980072696, 3957.861474848381, 4004.7797371144575, 4068.328725760246, 3231.850819205226, 4094.3858421236755, 4102.364133559303, 4131.690123240769, 4135.565765594614, 3167.129039514129, 3555.6977656899194, 4025.0138716014617, 4040.8181631826637, 4088.0188754698806, 3804.3912973924316, 3900.6294402031763, 4038.798060096998, 4070.7354021988476, 4133.731307465006, 3310.2386451116136, 3530.6116327203986, 3553.271579326258, 3559.7330200235087, 3560.9109772255642, 5266.8419947401635, 5267.920981185285, 5271.65734227274, 5288.814891478818, 5298.311219541473, 3544.521489598897, 4038.515378154611, 4698.671093023489, 4723.267893476516, 4740.463851839249, 5480.808171713971, 5694.402087780765, 5719.656820590778, 5721.628094173865, 5742.196092547047, 3474.583050034012, 3727.683146932514, 3899.98050009751, 3902.6682731967817, 3942.8619998299923, 4876.707397556158, 5221.190715439113, 5728.82813515591, 6001.849953751147, 6077.544184186159, 3632.7648599476306, 3640.672339489459, 3810.180811125566, 3898.8245528951265, 3952.2885880541203, 3594.3170797548905, 3621.4386770050683, 3874.3994680824517, 3894.1822217334675, 3977.3636218204947, 4039.4748682671066, 4599.896024953987, 4701.083288758659, 4710.505396933273, 4728.0, 4937.432195297515, 5245.0, 5600.60795744294, 5667.809759950374, 5787.739551720183, 1148.5004023249746, 1361.6255529729206, 1369.9452021919012, 1370.465518447806, 1379.2207402817376, 788.0, 789.8696715041954, 811.472542847154, 836.9203727191855, 848.211163617829, 4928.753562321869, 5191.532762051434, 5192.676686920374, 5245.868853278622, 5352.437994010573, 5463.672179669153, 5814.470705243233, 5859.334003028612, 5885.793997210163, 5919.763209471659, 4646.512116227815, 5016.347874776328, 5088.440271570124, 5132.230165475134, 5421.183903392329, 2596.9740302596733, 2647.404334024829, 2928.4143171365813, 2953.291210109558, 3127.1712996056062, 5336.252924590561, 5637.744902039135, 5952.672603006789, 5959.106134079836, 6074.726637301332, 5343.047965998791, 5523.28197334344, 6128.754849805958, 6138.846528836815, 6142.877142457126, 5586.3520261522945, 5952.476118150151, 6138.600990935575, 6148.264022510119, 6197.9093798093045, 4850.151223535797, 5613.859653508695, 5761.769529218785, 5922.822315330547, 5954.315253745702, 5017.598592112549, 5799.5070419014855, 5811.970940145441, 5825.563082768725, 5842.1820945066875, 5647.491725744702, 5951.761929522944, 6114.6942652868065, 6118.143459915526, 6124.693765311806, 3275.5250488679485, 3445.9655403445645, 3464.1859163097156, 3477.009052420048, 3477.8434970425888, 4428.446444194476, 4447.799849006868, 4716.929246061243, 4953.504649535018, 5018.824341148043, 4223.809928553161, 4438.647516816092, 4446.909952322403, 4515.367848501278, 4982.451930287738, 4587.724736515686, 4724.881877953078, 4730.810767569259, 4804.47764923418, 4876.536729010738, 4281.743095414286, 4645.862903064493, 4797.529272424635, 4825.914169311876, 5143.125668636266, 4678.625709943264, 4752.669252609275, 5546.417626149197, 5583.776648933997, 5608.551315894801, 5103.668261562986, 5462.617616766947, 5487.829249770123, 5487.945120548743, 5511.283533140666], "value": 4028.8386170812337, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.6865098799612, 730.7223255163037, 745.6197339356926, 748.128430378609, 788.8934993775842, 15160.0, 15720.135392553406, 15742.921285393586, 15744.921275393635, 15819.683606327866, 14166.079204851678, 14368.928155359208, 14421.206833624146, 14429.927850360733, 14657.0, 904.141065987313, 927.1516562345446, 928.3269629518612, 928.5403725156057, 929.4376901974309, 904.1365495951351, 925.9768505787342, 926.2867591954191, 926.7775733823901, 926.9860952085725, 970.6845275285525, 971.946542940138, 972.8686627305294, 973.3770386952356, 976.3995142987047, 963.889152747436, 964.3635200767495, 966.7534778631474, 967.1295833749647, 972.9124378805906, 713.6324792731747, 714.1822613108014, 714.2322003845886, 715.0204220218283, 715.4240836126916, 13316.267605281706, 13587.660308492366, 13638.590842274747, 13646.658833529142, 13673.316334183251, 9836.704898853046, 11197.216194866363, 11210.775784484345, 11314.547418103266, 11745.119116066244, 9235.445873247627, 9389.76525586859, 9516.0, 9593.42439453628, 10183.388996660224, 3343.7157841583253, 3397.69420752131, 3458.2564748579175, 3460.1003739028033, 3512.0517460285837, 62426.68786656038, 73017.53964920725, 73560.0, 82643.347133057, 82981.58509207415, 9344.439333640003, 9433.433993960247, 9645.131938125602, 9692.0, 9996.800063998679, 21638.24266150677, 22190.223342182948, 22202.66695999561, 22215.444613884458, 22228.55542889133, 7544.773656790305, 7563.0, 7585.317321441097, 7661.157272699998, 7942.3646108311195, 3347.3974684557015, 4003.9589706676475, 4044.3906255450784, 4110.383442483591, 4393.7363758174615, 3419.7948123112196, 3493.406120959443, 3538.1862171700136, 3542.6811586956783, 3543.114221444639, 618.1315252070848, 618.832915112917, 630.7161777200279, 632.4434497642045, 638.0939066525452, 471.220130683719, 480.7043668144066, 510.86206724185007, 544.311446020784, 565.3272605599406, 7153.642317884085, 7556.168821429638, 7661.655225514865, 7697.461177717506, 7709.151993280735, 491.63864559548705, 519.1563708972919, 533.5198321510671, 548.3502050070679, 561.5451484297732, 3638.4542318652416, 3652.2878038782183, 3659.076329386465, 3710.6289371062676, 3765.4163604641326, 79653.0, 87553.54949521145, 98189.50905245428, 101012.98987010182, 101313.97372052666, 82226.17773822186, 85597.0, 86485.0, 86523.0, 87491.0, 57663.711681441324, 73760.26239737641, 75234.0, 75470.86793698098, 78942.0, 8075.313598344206, 8197.79505512367, 8201.30288925448, 8270.875936860832, 8418.284445822173, 45620.54379456296, 46946.53053469422, 47315.76342118267, 48235.5176448231, 49065.01869962509, 63071.42321442001, 64396.712065757485, 67427.0, 67847.66076169588, 68599.65700171467, 10958.452077396258, 11086.778264434828, 11253.88746112561, 11424.771504570028, 11546.884531154581, 11620.709482263012, 11629.88370116288, 11906.345151016778, 12228.144029917821, 12267.386630668252, 11327.207095503565, 11601.477933492844, 11616.361100139235, 11854.94072529632, 11919.821202681793, 9463.337566370288, 11385.943070284595, 11584.536618535165, 11657.941710291394, 11957.222780519238, 11050.557977680803, 11105.666829995112, 11125.721856953318, 11195.496202670736, 12177.57378491784, 9599.952000239955, 9703.320767546204, 9716.076972687584, 9731.756706082404, 9978.60085596568, 9437.952810235905, 9623.182029527568, 9670.85493717609, 9688.806223875625, 9901.05939935705], "value": 6824.921827194185, "unit": "score"}], "name": "dromaeo_css", "value": 7172.977701425705, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 113.0, 112.0, 112.0, 113.0, 112.0, 113.0, 113.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 138.0, 201.0, 131.0, 160.0, 153.0, 117.0, 165.0, 119.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 187.0, 183.0, 182.0, 184.0, 183.0, 182.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 57.0, 126.0, 57.0, 61.0, 56.0, 75.0, 132.0, 77.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 134.0, 79.0, 80.0, 77.0, 102.0, 81.0, 85.0, 101.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 92.0, 91.0, 92.0, 91.0, 91.0, 87.0, 94.0, 89.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 99.0, 142.0, 89.0, 96.0, 95.0, 95.0, 97.0, 103.0, 117.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 84.0, 85.0, 83.0, 86.0, 84.0, 84.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 58.0, 56.0, 58.0, 58.0, 122.0, 58.0, 56.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 68.0, 70.0, 74.0, 70.0, 63.0, 70.0, 71.0, 67.0, 66.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 86.0, 74.0, 97.0, 103.0, 94.0, 84.0, 86.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 142.0, 141.0, 142.0, 135.0, 134.0, 215.0, 140.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 155.0, 162.0, 163.0, 162.0, 162.0, 162.0, 158.0, 164.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 65.0, 66.0, 70.0, 73.0, 79.0, 80.0, 76.0, 63.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1494.1999999999996, "alertThreshold": 2.0}]} 12:32:24 INFO - Return code: 0 12:32:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:32:24 ERROR - # TBPL SUCCESS # 12:32:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:32:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:32:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:32:24 INFO - 2016-05-16 12:32:24.605 screenresolution[1674:9747] starting screenresolution argv=screenresolution get 12:32:24 INFO - 2016-05-16 12:32:24.607 screenresolution[1674:9747] Display 0: 1600x1200x32@60 12:32:24 INFO - 2016-05-16 12:32:24.613 screenresolution[1675:9750] starting screenresolution argv=screenresolution list 12:32:24 INFO - Available Modes on Display 0 12:32:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:32:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:32:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:32:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:32:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:32:24 INFO - Intel Iris: 12:32:24 INFO - Chipset Model: Intel Iris 12:32:24 INFO - Type: GPU 12:32:24 INFO - Bus: Built-In 12:32:24 INFO - VRAM (Dynamic, Max): 1536 MB 12:32:24 INFO - Vendor: Intel (0x8086) 12:32:24 INFO - Device ID: 0x0a2e 12:32:24 INFO - Revision ID: 0x0009 12:32:24 INFO - Displays: 12:32:24 INFO - Display: 12:32:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:32:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:32:24 INFO - Display Serial Number: 1600x1200 60 12:32:24 INFO - Main Display: Yes 12:32:24 INFO - Mirror: Off 12:32:24 INFO - Online: Yes 12:32:24 INFO - Rotation: Supported 12:32:24 INFO - Return code: 0 12:32:24 INFO - Running post-action listener: _resource_record_post_action 12:32:24 INFO - ##### 12:32:24 INFO - ##### Finished run-tests step (success) 12:32:24 INFO - ##### 12:32:24 INFO - Running post-run listener: _resource_record_post_run 12:32:24 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 36787712; Write bytes: 694963200; Read time: 242; Write time: 1912 12:32:24 INFO - TinderboxPrint: CPU usage
53.9% 12:32:24 INFO - TinderboxPrint: I/O read bytes / time
36,787,712 / 242 12:32:24 INFO - TinderboxPrint: I/O write bytes / time
694,963,200 / 1,912 12:32:24 INFO - TinderboxPrint: CPU idle
2,239.2 (46.1%) 12:32:24 INFO - TinderboxPrint: CPU system
967.5 (19.9%) 12:32:24 INFO - TinderboxPrint: CPU user
1,651.0 (34.0%) 12:32:24 INFO - TinderboxPrint: Swap in / out
431,661,056 / 0 12:32:24 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 384735744; Write bytes: 378545664; Read time: 30383; Write time: 772 12:32:24 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34997760; Write bytes: 296945152; Read time: 227; Write time: 1089 12:32:25 INFO - Running post-run listener: _upload_blobber_files 12:32:25 INFO - Blob upload gear active. 12:32:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:32:25 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 12:32:25 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'] 12:32:25 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 12:32:25 INFO - (blobuploader) - INFO - Open directory for files ... 12:32:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:32:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:32:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:27 INFO - (blobuploader) - INFO - Done attempting. 12:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:32:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:28 INFO - (blobuploader) - INFO - Done attempting. 12:32:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:32:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:30 INFO - (blobuploader) - INFO - Done attempting. 12:32:30 INFO - (blobuploader) - INFO - Iteration through files over. 12:32:30 INFO - Return code: 0 12:32:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:32:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:32:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/845fa0c2334833cacb4150f965ca26a176a0562daa8df1d13a551901e2dde2575c0d8b151b62719c0114f204881799e756c2ba997ae6f5b17a96daa990e766cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be9822d8cb600dff9fb671a6176ba97aa3073f59fda4024717f52178a1c68ddba90a283ee1fc8d46f179386881505c4bbbbb7dc2890e63b7f131024c8e0b0016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95171105b5d9e3b6070d7bb0ec7be8f7147967a8936f443ace3cca744ee0a0f82a8adac515700478385be251db13f59a923771755be30d46dc4df80149a9a2d9"} 12:32:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:32:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:32:30 INFO - Contents: 12:32:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/845fa0c2334833cacb4150f965ca26a176a0562daa8df1d13a551901e2dde2575c0d8b151b62719c0114f204881799e756c2ba997ae6f5b17a96daa990e766cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be9822d8cb600dff9fb671a6176ba97aa3073f59fda4024717f52178a1c68ddba90a283ee1fc8d46f179386881505c4bbbbb7dc2890e63b7f131024c8e0b0016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95171105b5d9e3b6070d7bb0ec7be8f7147967a8936f443ace3cca744ee0a0f82a8adac515700478385be251db13f59a923771755be30d46dc4df80149a9a2d9"} 12:32:30 INFO - Running post-run listener: copy_logs_to_upload_dir 12:32:30 INFO - Copying logs to upload dir... 12:32:30 INFO - mkdir: /builds/slave/test/build/upload/logs 12:32:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.142025 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-16 12:32:30.834799) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 12:32:30.839923) ========= 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.lfj00EcIfp/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.FxQIUfZhva/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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/845fa0c2334833cacb4150f965ca26a176a0562daa8df1d13a551901e2dde2575c0d8b151b62719c0114f204881799e756c2ba997ae6f5b17a96daa990e766cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be9822d8cb600dff9fb671a6176ba97aa3073f59fda4024717f52178a1c68ddba90a283ee1fc8d46f179386881505c4bbbbb7dc2890e63b7f131024c8e0b0016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95171105b5d9e3b6070d7bb0ec7be8f7147967a8936f443ace3cca744ee0a0f82a8adac515700478385be251db13f59a923771755be30d46dc4df80149a9a2d9"} build_url:https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011018 build_url: 'https://queue.taskcluster.net/v1/task/TUcHInpwRW6bCLwNAQgPkw/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/845fa0c2334833cacb4150f965ca26a176a0562daa8df1d13a551901e2dde2575c0d8b151b62719c0114f204881799e756c2ba997ae6f5b17a96daa990e766cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be9822d8cb600dff9fb671a6176ba97aa3073f59fda4024717f52178a1c68ddba90a283ee1fc8d46f179386881505c4bbbbb7dc2890e63b7f131024c8e0b0016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95171105b5d9e3b6070d7bb0ec7be8f7147967a8936f443ace3cca744ee0a0f82a8adac515700478385be251db13f59a923771755be30d46dc4df80149a9a2d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 12:32:30.875427) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:32:30.875752) ========= 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.lfj00EcIfp/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.FxQIUfZhva/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.005114 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:32:30.925263) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 12:32:30.928635) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 12:32:30.929021) ========= ========= Total master_lag: 0.74 =========