builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0257 starttime: 1464147694.24 results: success (0) buildid: 20160524194536 builduid: 8c9f3175870843f1a1c685d4c2d13fc7 revision: ea60f9e94a807a0be57df93c9716426b5651b21b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.240938) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.241836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.242244) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.266125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.266832) ========= 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.noPow6p16F/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.qN2h9X77au/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-24 20:41:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.5M=0.001s 2016-05-24 20:41:34 (15.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118527 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.408458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.409062) ========= 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.noPow6p16F/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.qN2h9X77au/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.025279 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:34.461459) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 20:41:34.462322) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ea60f9e94a807a0be57df93c9716426b5651b21b --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 ea60f9e94a807a0be57df93c9716426b5651b21b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.noPow6p16F/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.qN2h9X77au/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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-24 20:41:34,541 truncating revision to first 12 chars 2016-05-24 20:41:34,542 Setting DEBUG logging. 2016-05-24 20:41:34,542 attempt 1/10 2016-05-24 20:41:34,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ea60f9e94a80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 20:41:35,793 unpacking tar archive at: fx-team-ea60f9e94a80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.501934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 20:41:35.985868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:35.986384) ========= script_repo_revision: ea60f9e94a807a0be57df93c9716426b5651b21b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:35.986938) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:35.987264) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 20:41:36.003463) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-24 20:41:36.003841) ========= /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.noPow6p16F/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.qN2h9X77au/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:41:36 INFO - MultiFileLogger online at 20160524 20:41:36 in /builds/slave/test 20:41:36 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 20:41:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:41:36 INFO - {'append_to_log': False, 20:41:36 INFO - 'base_work_dir': '/builds/slave/test', 20:41:36 INFO - 'blob_upload_branch': 'Fx-Team', 20:41:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:41:36 INFO - 'branch': 'Fx-Team', 20:41:36 INFO - 'buildbot_json_path': 'buildprops.json', 20:41:36 INFO - 'config_files': ('talos/mac_config.py',), 20:41:36 INFO - 'default_actions': ('clobber', 20:41:36 INFO - 'read-buildbot-config', 20:41:36 INFO - 'download-and-extract', 20:41:36 INFO - 'populate-webroot', 20:41:36 INFO - 'create-virtualenv', 20:41:36 INFO - 'install', 20:41:36 INFO - 'run-tests'), 20:41:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:41:36 INFO - 'download_minidump_stackwalk': True, 20:41:36 INFO - 'download_symbols': 'ondemand', 20:41:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:41:36 INFO - 'tooltool.py': '/tools/tooltool.py', 20:41:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:41:36 INFO - '/tools/misc-python/virtualenv.py')}, 20:41:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:41:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:41:36 INFO - 'installer_path': 'installer.exe', 20:41:36 INFO - 'log_level': 'info', 20:41:36 INFO - 'log_name': 'talos', 20:41:36 INFO - 'log_to_console': True, 20:41:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:41:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:41:36 INFO - 'opt_config_files': (), 20:41:36 INFO - 'pip_index': False, 20:41:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:41:36 INFO - 'cmd': ('bash', 20:41:36 INFO - '-c', 20:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:41:36 INFO - 'enabled': True, 20:41:36 INFO - 'halt_on_failure': False, 20:41:36 INFO - 'name': 'check_screen_resolution'},), 20:41:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:41:36 INFO - 'cmd': ('bash', 20:41:36 INFO - '-c', 20:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:41:36 INFO - 'enabled': True, 20:41:36 INFO - 'halt_on_failure': False, 20:41:36 INFO - 'name': 'check_screen_resolution'},), 20:41:36 INFO - 'run_cmd_checks_enabled': True, 20:41:36 INFO - 'sps_profile': False, 20:41:36 INFO - 'sps_profile_interval': 0, 20:41:36 INFO - 'suite': 'dromaeojs', 20:41:36 INFO - 'system_bits': '32', 20:41:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:41:36 INFO - 'title': 't-yosemite-r7-0257', 20:41:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:41:36 INFO - 'use_talos_json': True, 20:41:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:41:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:41:36 INFO - 'work_dir': 'build'} 20:41:36 INFO - ##### 20:41:36 INFO - ##### Running clobber step. 20:41:36 INFO - ##### 20:41:36 INFO - Running pre-action listener: _resource_record_pre_action 20:41:36 INFO - Running main action method: clobber 20:41:36 INFO - rmtree: /builds/slave/test/build 20:41:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:41:37 INFO - Running post-action listener: _resource_record_post_action 20:41:37 INFO - ##### 20:41:37 INFO - ##### Finished clobber step (success) 20:41:37 INFO - ##### 20:41:37 INFO - ##### 20:41:37 INFO - ##### Running read-buildbot-config step. 20:41:37 INFO - ##### 20:41:37 INFO - Running pre-action listener: _resource_record_pre_action 20:41:37 INFO - Running main action method: read_buildbot_config 20:41:37 INFO - Using buildbot properties: 20:41:37 INFO - { 20:41:37 INFO - "project": "", 20:41:37 INFO - "product": "firefox", 20:41:37 INFO - "script_repo_revision": "production", 20:41:37 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:41:37 INFO - "repository": "", 20:41:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:41:37 INFO - "buildid": "20160524194536", 20:41:37 INFO - "pgo_build": "False", 20:41:37 INFO - "basedir": "/builds/slave/test", 20:41:37 INFO - "buildnumber": 723, 20:41:37 INFO - "slavename": "t-yosemite-r7-0257", 20:41:37 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:41:37 INFO - "platform": "yosemite_r7", 20:41:37 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:41:37 INFO - "branch": "fx-team", 20:41:37 INFO - "repo_path": "integration/fx-team", 20:41:37 INFO - "revision": "ea60f9e94a807a0be57df93c9716426b5651b21b", 20:41:37 INFO - "stage_platform": "macosx64", 20:41:37 INFO - "builduid": "8c9f3175870843f1a1c685d4c2d13fc7", 20:41:37 INFO - "slavebuilddir": "test" 20:41:37 INFO - } 20:41:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:41:37 INFO - Running post-action listener: _resource_record_post_action 20:41:37 INFO - ##### 20:41:37 INFO - ##### Finished read-buildbot-config step (success) 20:41:37 INFO - ##### 20:41:37 INFO - ##### 20:41:37 INFO - ##### Running download-and-extract step. 20:41:37 INFO - ##### 20:41:37 INFO - Running pre-action listener: _resource_record_pre_action 20:41:37 INFO - Running main action method: download_and_extract 20:41:37 INFO - mkdir: /builds/slave/test/build/tests 20:41:37 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')]} 20:41:37 INFO - https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:41:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:41:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:41:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:39 INFO - Downloaded 1199 bytes. 20:41:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:41:39 INFO - Using the following test package requirements: 20:41:39 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:41:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:41:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:41:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'jsshell-mac.zip'], 20:41:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:41:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:41:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:41:39 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:41:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:41:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:41:39 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:41:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:41:39 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')]} 20:41:39 INFO - https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:41:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:41:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:41:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:40 INFO - Downloaded 18632964 bytes. 20:41:40 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'] 20:41:40 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 20:41:43 INFO - Return code: 0 20:41:43 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 20:41:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:41:43 INFO - https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:41:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:41:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:41:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:45 INFO - Downloaded 18632964 bytes. 20:41:45 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'] 20:41:45 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 20:41:49 INFO - Return code: 0 20:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:41:49 INFO - https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:41:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:41:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:41:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:50 INFO - Downloaded 10840359 bytes. 20:41:50 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'] 20:41:50 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 20:41:50 INFO - Return code: 0 20:41: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')]} 20:41:50 INFO - https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:41:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:41:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:41:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GpOgHQipQTO5pjXGXU8ENg/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 20:41:54 INFO - Downloaded 110241743 bytes. 20:41:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:41:54 INFO - mkdir: /builds/slave/test/properties 20:41:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:41:54 INFO - Writing to file /builds/slave/test/properties/build_url 20:41:54 INFO - Contents: 20:41:54 INFO - build_url:https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:41:55 INFO - Running post-action listener: _resource_record_post_action 20:41:55 INFO - Running post-action listener: set_extra_try_arguments 20:41:55 INFO - ##### 20:41:55 INFO - ##### Finished download-and-extract step (success) 20:41:55 INFO - ##### 20:41:55 INFO - ##### 20:41:55 INFO - ##### Running populate-webroot step. 20:41:55 INFO - ##### 20:41:55 INFO - Running pre-action listener: _resource_record_pre_action 20:41:55 INFO - Running main action method: populate_webroot 20:41:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:41:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:41:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:41:55 INFO - '--tppagecycles', 20:41:55 INFO - '7'], 20:41:55 INFO - 'tests': ['tsvgm']}}, 20:41:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:41:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:41:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:41:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:41:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:41:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:41:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['damp', 'tps']}, 20:41:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'tests': ['damp', 'tps']}, 20:41:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['dromaeo_dom']}, 20:41:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:41:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['a11yr', 20:41:55 INFO - 'ts_paint', 20:41:55 INFO - 'tpaint', 20:41:55 INFO - 'sessionrestore', 20:41:55 INFO - 'sessionrestore_no_auto_restore', 20:41:55 INFO - 'tabpaint']}, 20:41:55 INFO - 'other-e10s': {'tests': ['a11yr', 20:41:55 INFO - 'ts_paint', 20:41:55 INFO - 'tpaint', 20:41:55 INFO - 'sessionrestore', 20:41:55 INFO - 'sessionrestore_no_auto_restore', 20:41:55 INFO - 'tabpaint']}, 20:41:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:41:55 INFO - 'ts_paint', 20:41:55 INFO - 'tpaint', 20:41:55 INFO - 'sessionrestore', 20:41:55 INFO - 'sessionrestore_no_auto_restore', 20:41:55 INFO - 'tabpaint']}, 20:41:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:41:55 INFO - 'ts_paint', 20:41:55 INFO - 'tpaint', 20:41:55 INFO - 'sessionrestore', 20:41:55 INFO - 'sessionrestore_no_auto_restore', 20:41:55 INFO - 'tabpaint']}, 20:41:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['a11yr', 20:41:55 INFO - 'ts_paint', 20:41:55 INFO - 'tpaint', 20:41:55 INFO - 'sessionrestore', 20:41:55 INFO - 'sessionrestore_no_auto_restore', 20:41:55 INFO - 'tabpaint']}, 20:41:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['a11yr', 20:41:55 INFO - 'ts_paint', 20:41:55 INFO - 'tpaint', 20:41:55 INFO - 'sessionrestore', 20:41:55 INFO - 'sessionrestore_no_auto_restore', 20:41:55 INFO - 'tabpaint']}, 20:41:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['tsvgx', 20:41:55 INFO - 'tsvgr_opacity', 20:41:55 INFO - 'tart', 20:41:55 INFO - 'tscrollx', 20:41:55 INFO - 'cart']}, 20:41:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:41:55 INFO - 'tsvgr_opacity', 20:41:55 INFO - 'tart', 20:41:55 INFO - 'tscrollx', 20:41:55 INFO - 'cart']}, 20:41:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'talos_options': ['--disable-e10s'], 20:41:55 INFO - 'tests': ['tp5o']}, 20:41:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'tests': ['tp5o']}, 20:41:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'talos_options': ['--disable-e10s', 20:41:55 INFO - '--xperf_path', 20:41:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:41:55 INFO - 'tests': ['tp5n']}, 20:41:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:41:55 INFO - 'talos_options': ['--xperf_path', 20:41:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:41:55 INFO - 'tests': ['tp5n']}}, 20:41:55 INFO - 'talos.zip': {'path': '', 20:41:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:41:55 INFO - Running post-action listener: _resource_record_post_action 20:41:55 INFO - ##### 20:41:55 INFO - ##### Finished populate-webroot step (success) 20:41:55 INFO - ##### 20:41:55 INFO - ##### 20:41:55 INFO - ##### Running create-virtualenv step. 20:41:55 INFO - ##### 20:41:55 INFO - Running pre-action listener: _resource_record_pre_action 20:41:55 INFO - Running main action method: create_virtualenv 20:41:55 INFO - Creating virtualenv /builds/slave/test/build/venv 20:41:55 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 20:41:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:41:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:41:55 INFO - Using real prefix '/tools/python27' 20:41:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:41:56 INFO - Installing distribute.............................................................................................................................................................................................done. 20:41:58 INFO - Installing pip.................done. 20:41:58 INFO - Return code: 0 20:41:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:41:58 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')]} 20:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:58 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')]} 20:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:58 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:41:58 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 20:41:58 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 20:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:58 INFO - 'HOME': '/Users/cltbld', 20:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:58 INFO - 'LOGNAME': 'cltbld', 20:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:58 INFO - 'MOZ_NO_REMOTE': '1', 20:41:58 INFO - 'NO_EM_RESTART': '1', 20:41:58 INFO - 'PAGER': '/bin/cat', 20:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:58 INFO - 'PWD': '/builds/slave/test', 20:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:58 INFO - 'SHELL': '/bin/bash', 20:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:41:58 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:58 INFO - 'USER': 'cltbld', 20:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:58 INFO - 'XPC_FLAGS': '0x0', 20:41:58 INFO - 'XPC_SERVICE_NAME': '0', 20:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:59 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 20:41:59 INFO - Cleaning up... 20:41:59 INFO - Return code: 0 20:41:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:41:59 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')]} 20:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:59 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')]} 20:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:41:59 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:41:59 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 20:41:59 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 20:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:41:59 INFO - 'HOME': '/Users/cltbld', 20:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:41:59 INFO - 'LOGNAME': 'cltbld', 20:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:59 INFO - 'MOZ_NO_REMOTE': '1', 20:41:59 INFO - 'NO_EM_RESTART': '1', 20:41:59 INFO - 'PAGER': '/bin/cat', 20:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:59 INFO - 'PWD': '/builds/slave/test', 20:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:41:59 INFO - 'SHELL': '/bin/bash', 20:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:41:59 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:41:59 INFO - 'USER': 'cltbld', 20:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:59 INFO - 'XPC_FLAGS': '0x0', 20:41:59 INFO - 'XPC_SERVICE_NAME': '0', 20:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:59 INFO - Downloading/unpacking psutil>=3.1.1 20:41:59 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 20:41:59 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 20:41:59 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 20:41:59 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 20:41:59 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 20:41:59 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 20:42:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:42:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:42:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:42:01 INFO - Installing collected packages: psutil 20:42:01 INFO - Running setup.py install for psutil 20:42:02 INFO - building 'psutil._psutil_osx' extension 20:42:02 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 20:42:02 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 20:42:02 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 20:42:02 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 20:42:02 INFO - building 'psutil._psutil_posix' extension 20:42:02 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 20:42:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:42:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:42:02 INFO - ^ 20:42:02 INFO - 1 warning generated. 20:42:02 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 20:42:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:42:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:42:03 INFO - Successfully installed psutil 20:42:03 INFO - Cleaning up... 20:42:03 INFO - Return code: 0 20:42:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:42:03 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')]} 20:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:03 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')]} 20:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:03 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:03 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 20:42:03 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 20:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:03 INFO - 'HOME': '/Users/cltbld', 20:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:03 INFO - 'LOGNAME': 'cltbld', 20:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:03 INFO - 'MOZ_NO_REMOTE': '1', 20:42:03 INFO - 'NO_EM_RESTART': '1', 20:42:03 INFO - 'PAGER': '/bin/cat', 20:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:03 INFO - 'PWD': '/builds/slave/test', 20:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:03 INFO - 'SHELL': '/bin/bash', 20:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:03 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:03 INFO - 'USER': 'cltbld', 20:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:03 INFO - 'XPC_FLAGS': '0x0', 20:42:03 INFO - 'XPC_SERVICE_NAME': '0', 20:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:42: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 20:42: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 20:42: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 20:42: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 20:42: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 20:42: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 20:42:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:42:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:42:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:42:05 INFO - Installing collected packages: mozsystemmonitor 20:42:05 INFO - Running setup.py install for mozsystemmonitor 20:42:06 INFO - Successfully installed mozsystemmonitor 20:42:06 INFO - Cleaning up... 20:42:06 INFO - Return code: 0 20:42:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:42:06 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')]} 20:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:06 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')]} 20:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:06 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:06 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 20:42:06 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 20:42:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:06 INFO - 'HOME': '/Users/cltbld', 20:42:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:06 INFO - 'LOGNAME': 'cltbld', 20:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:06 INFO - 'MOZ_NO_REMOTE': '1', 20:42:06 INFO - 'NO_EM_RESTART': '1', 20:42:06 INFO - 'PAGER': '/bin/cat', 20:42:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:06 INFO - 'PWD': '/builds/slave/test', 20:42:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:06 INFO - 'SHELL': '/bin/bash', 20:42:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:06 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:06 INFO - 'USER': 'cltbld', 20:42:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:06 INFO - 'XPC_FLAGS': '0x0', 20:42:06 INFO - 'XPC_SERVICE_NAME': '0', 20:42:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:06 INFO - Downloading/unpacking blobuploader==1.2.4 20:42:06 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 20:42:06 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 20:42:06 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 20:42:06 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 20:42:06 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 20:42:06 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 20:42:08 INFO - Downloading blobuploader-1.2.4.tar.gz 20:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:42:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:42: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 20:42: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 20:42: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 20:42: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 20:42: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 20:42: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 20:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:42:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:42:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:42:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:42:10 INFO - Downloading docopt-0.6.1.tar.gz 20:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:42:10 INFO - Installing collected packages: blobuploader, requests, docopt 20:42:10 INFO - Running setup.py install for blobuploader 20:42:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:42:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:42:10 INFO - Running setup.py install for requests 20:42:10 INFO - Running setup.py install for docopt 20:42:10 INFO - Successfully installed blobuploader requests docopt 20:42:10 INFO - Cleaning up... 20:42:11 INFO - Return code: 0 20:42:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:11 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:11 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 20:42:11 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 20:42:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:11 INFO - 'HOME': '/Users/cltbld', 20:42:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:11 INFO - 'LOGNAME': 'cltbld', 20:42:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:11 INFO - 'MOZ_NO_REMOTE': '1', 20:42:11 INFO - 'NO_EM_RESTART': '1', 20:42:11 INFO - 'PAGER': '/bin/cat', 20:42:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:11 INFO - 'PWD': '/builds/slave/test', 20:42:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:11 INFO - 'SHELL': '/bin/bash', 20:42:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:11 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:11 INFO - 'USER': 'cltbld', 20:42:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:11 INFO - 'XPC_FLAGS': '0x0', 20:42:11 INFO - 'XPC_SERVICE_NAME': '0', 20:42:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Ii7xRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-qt0RMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-vsAv3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-FjNBND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-vln2q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-mOsA69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:42:11 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-gHc7Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-I8GE_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-PWOVd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Zpk2wT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-VKwiHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-IYHjeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-5BlxPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-hybg5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-wb1cop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-V_olVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:42:12 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-AO7hwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:42:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:42:13 INFO - Running setup.py install for manifestparser 20:42:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:42:13 INFO - Running setup.py install for mozcrash 20:42:13 INFO - Running setup.py install for mozdebug 20:42:13 INFO - Running setup.py install for mozdevice 20:42:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:42:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:42:13 INFO - Running setup.py install for mozfile 20:42:13 INFO - Running setup.py install for mozhttpd 20:42:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:42:13 INFO - Running setup.py install for mozinfo 20:42:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Running setup.py install for mozInstall 20:42:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Running setup.py install for mozleak 20:42:14 INFO - Running setup.py install for mozlog 20:42:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Running setup.py install for moznetwork 20:42:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Running setup.py install for mozprocess 20:42:14 INFO - Running setup.py install for mozprofile 20:42:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:42:14 INFO - Running setup.py install for mozrunner 20:42:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:42:15 INFO - Running setup.py install for mozscreenshot 20:42:15 INFO - Running setup.py install for moztest 20:42:15 INFO - Running setup.py install for mozversion 20:42:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:42:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:42:15 INFO - Cleaning up... 20:42:15 INFO - Return code: 0 20:42:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:15 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:15 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 20:42:15 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 20:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:15 INFO - 'HOME': '/Users/cltbld', 20:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:15 INFO - 'LOGNAME': 'cltbld', 20:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:15 INFO - 'MOZ_NO_REMOTE': '1', 20:42:15 INFO - 'NO_EM_RESTART': '1', 20:42:15 INFO - 'PAGER': '/bin/cat', 20:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:15 INFO - 'PWD': '/builds/slave/test', 20:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:15 INFO - 'SHELL': '/bin/bash', 20:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:15 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:15 INFO - 'USER': 'cltbld', 20:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:15 INFO - 'XPC_FLAGS': '0x0', 20:42:15 INFO - 'XPC_SERVICE_NAME': '0', 20:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:42:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ohpjXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:42:15 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)) 20:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:42:15 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-K2Sir7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Gwem9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-D_jBmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-zCtSnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-6KRw9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-BFoZ6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-CiBDIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-7fheJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-GILtvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:42:16 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)) 20:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:42:16 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-xGKNek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:42:17 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)) 20:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:42:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-eUbcPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:42:17 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)) 20:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:42:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-UppAbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:42:17 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)) 20:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:42:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-bkSutN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:42:17 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)) 20:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-N4ICOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:17 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)) 20:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:42:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Sls8c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:42:17 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)) 20:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:42:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-w3I1mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:42:17 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)) 20:42:17 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)) 20:42:17 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)) 20:42:17 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)) 20:42:17 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)) 20:42:17 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)) 20:42:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:42:17 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 20:42:17 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 20:42:17 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 20:42:17 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 20:42:17 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 20:42:17 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 20:42:20 INFO - Downloading blessings-1.6.tar.gz 20:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:42:20 INFO - Installing collected packages: blessings 20:42:20 INFO - Running setup.py install for blessings 20:42:20 INFO - Successfully installed blessings 20:42:20 INFO - Cleaning up... 20:42:20 INFO - Return code: 0 20:42:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:42:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:42:20 INFO - Reading from file tmpfile_stdout 20:42:20 INFO - Current package versions: 20:42:20 INFO - blessings == 1.6 20:42:20 INFO - blobuploader == 1.2.4 20:42:20 INFO - docopt == 0.6.1 20:42:20 INFO - manifestparser == 1.1 20:42:20 INFO - mozInstall == 1.12 20:42:20 INFO - mozcrash == 0.17 20:42:20 INFO - mozdebug == 0.1 20:42:20 INFO - mozdevice == 0.48 20:42:20 INFO - mozfile == 1.2 20:42:20 INFO - mozhttpd == 0.7 20:42:20 INFO - mozinfo == 0.9 20:42:20 INFO - mozleak == 0.1 20:42:20 INFO - mozlog == 3.2 20:42:20 INFO - moznetwork == 0.27 20:42:20 INFO - mozprocess == 0.22 20:42:20 INFO - mozprofile == 0.28 20:42:20 INFO - mozrunner == 6.11 20:42:20 INFO - mozscreenshot == 0.1 20:42:20 INFO - mozsystemmonitor == 0.3 20:42:20 INFO - moztest == 0.7 20:42:20 INFO - mozversion == 1.4 20:42:20 INFO - psutil == 3.1.1 20:42:20 INFO - requests == 1.2.3 20:42:20 INFO - wsgiref == 0.1.2 20:42:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:20 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:20 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 20:42:20 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 20:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:20 INFO - 'HOME': '/Users/cltbld', 20:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:20 INFO - 'LOGNAME': 'cltbld', 20:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:20 INFO - 'MOZ_NO_REMOTE': '1', 20:42:20 INFO - 'NO_EM_RESTART': '1', 20:42:20 INFO - 'PAGER': '/bin/cat', 20:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:20 INFO - 'PWD': '/builds/slave/test', 20:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:20 INFO - 'SHELL': '/bin/bash', 20:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:20 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:20 INFO - 'USER': 'cltbld', 20:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:20 INFO - 'XPC_FLAGS': '0x0', 20:42:20 INFO - 'XPC_SERVICE_NAME': '0', 20:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 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)) 20:42:20 INFO - Cleaning up... 20:42:20 INFO - Return code: 0 20:42:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:20 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:20 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 20:42:20 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 20:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:20 INFO - 'HOME': '/Users/cltbld', 20:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:20 INFO - 'LOGNAME': 'cltbld', 20:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:20 INFO - 'MOZ_NO_REMOTE': '1', 20:42:20 INFO - 'NO_EM_RESTART': '1', 20:42:20 INFO - 'PAGER': '/bin/cat', 20:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:20 INFO - 'PWD': '/builds/slave/test', 20:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:20 INFO - 'SHELL': '/bin/bash', 20:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:20 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:20 INFO - 'USER': 'cltbld', 20:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:20 INFO - 'XPC_FLAGS': '0x0', 20:42:20 INFO - 'XPC_SERVICE_NAME': '0', 20:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:21 INFO - Downloading/unpacking jsonschema 20:42:21 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 20:42:21 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 20:42:21 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 20:42:21 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 20:42:21 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 20:42:21 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 20:42:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:42:23 INFO - Downloading/unpacking functools32 (from jsonschema) 20:42:23 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 20:42:23 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 20:42:23 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 20:42:23 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 20:42:23 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 20:42:23 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 20:42:23 INFO - Downloading functools32-3.2.3-2.tar.gz 20:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:42:24 INFO - warning: no files found matching '*.txt' 20:42:24 INFO - no previously-included directories found matching 'build' 20:42:24 INFO - no previously-included directories found matching 'dist' 20:42:24 INFO - no previously-included directories found matching '.git*' 20:42:24 INFO - Installing collected packages: jsonschema, functools32 20:42:24 INFO - Running setup.py install for functools32 20:42:24 INFO - warning: no files found matching '*.txt' 20:42:24 INFO - no previously-included directories found matching 'build' 20:42:24 INFO - no previously-included directories found matching 'dist' 20:42:24 INFO - no previously-included directories found matching '.git*' 20:42:24 INFO - Successfully installed jsonschema functools32 20:42:24 INFO - Cleaning up... 20:42:24 INFO - Return code: 0 20:42:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:42:24 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')]} 20:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:24 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')]} 20:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:42:24 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 0x10d2dea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2e4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9843c55bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, '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.noPow6p16F/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.qN2h9X77au/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 20:42:24 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 20:42:24 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 20:42:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:42:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:42:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:42:24 INFO - 'HOME': '/Users/cltbld', 20:42:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:42:24 INFO - 'LOGNAME': 'cltbld', 20:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:24 INFO - 'MOZ_NO_REMOTE': '1', 20:42:24 INFO - 'NO_EM_RESTART': '1', 20:42:24 INFO - 'PAGER': '/bin/cat', 20:42:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:24 INFO - 'PWD': '/builds/slave/test', 20:42:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:42:24 INFO - 'SHELL': '/bin/bash', 20:42:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:42:24 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:42:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:42:24 INFO - 'USER': 'cltbld', 20:42:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:42:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:24 INFO - 'XPC_FLAGS': '0x0', 20:42:24 INFO - 'XPC_SERVICE_NAME': '0', 20:42:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:24 INFO - Downloading/unpacking flake8 20:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:42:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:42:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:42:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:42:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:42:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:27 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 20:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:42:28 INFO - no previously-included directories found matching 'docs/_build' 20:42:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:42:28 INFO - Running setup.py install for pep8 20:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:42:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:42:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:42:28 INFO - no previously-included directories found matching 'docs/_build' 20:42:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:42:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:42:28 INFO - Cleaning up... 20:42:28 INFO - Return code: 0 20:42:28 INFO - Running post-action listener: _resource_record_post_action 20:42:28 INFO - Running post-action listener: _start_resource_monitoring 20:42:28 INFO - Starting resource monitoring. 20:42:28 INFO - ##### 20:42:28 INFO - ##### Finished create-virtualenv step (success) 20:42:28 INFO - ##### 20:42:28 INFO - ##### 20:42:28 INFO - ##### Running install step. 20:42:28 INFO - ##### 20:42:28 INFO - Running pre-action listener: _resource_record_pre_action 20:42:28 INFO - Running main action method: install 20:42:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:42:28 INFO - Reading from file tmpfile_stdout 20:42:28 INFO - Detecting whether we're running mozinstall >=1.0... 20:42:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:42:29 INFO - Reading from file tmpfile_stdout 20:42:29 INFO - Output received: 20:42:29 INFO - Usage: mozinstall [options] installer 20:42:29 INFO - Options: 20:42:29 INFO - -h, --help show this help message and exit 20:42:29 INFO - -d DEST, --destination=DEST 20:42:29 INFO - Directory to install application into. [default: 20:42:29 INFO - "/builds/slave/test"] 20:42:29 INFO - --app=APP Application being installed. [default: firefox] 20:42:29 INFO - mkdir: /builds/slave/test/build/application 20:42:29 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'] 20:42:29 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 20:43:02 INFO - Reading from file tmpfile_stdout 20:43:02 INFO - Output received: 20:43:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:43:02 INFO - Running post-action listener: _resource_record_post_action 20:43:02 INFO - ##### 20:43:02 INFO - ##### Finished install step (success) 20:43:02 INFO - ##### 20:43:02 INFO - ##### 20:43:02 INFO - ##### Running run-tests step. 20:43:02 INFO - ##### 20:43:02 INFO - Running pre-action listener: _resource_record_pre_action 20:43:02 INFO - Running main action method: run_tests 20:43:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:43:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:43:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:43:02 INFO - 2016-05-24 20:43:02.434 screenresolution[1653:7669] starting screenresolution argv=screenresolution get 20:43:02 INFO - 2016-05-24 20:43:02.437 screenresolution[1653:7669] Display 0: 1600x1200x32@60 20:43:02 INFO - 2016-05-24 20:43:02.443 screenresolution[1654:7677] starting screenresolution argv=screenresolution list 20:43:02 INFO - Available Modes on Display 0 20:43:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:43:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:43:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:43:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:43:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:43:02 INFO - Intel Iris: 20:43:02 INFO - Chipset Model: Intel Iris 20:43:02 INFO - Type: GPU 20:43:02 INFO - Bus: Built-In 20:43:02 INFO - VRAM (Dynamic, Max): 1536 MB 20:43:02 INFO - Vendor: Intel (0x8086) 20:43:02 INFO - Device ID: 0x0a2e 20:43:02 INFO - Revision ID: 0x0009 20:43:02 INFO - Displays: 20:43:02 INFO - Display: 20:43:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:43:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:43:02 INFO - Display Serial Number: 1600x1200 60 20:43:02 INFO - Main Display: Yes 20:43:02 INFO - Mirror: Off 20:43:02 INFO - Online: Yes 20:43:02 INFO - Rotation: Supported 20:43:02 INFO - Return code: 0 20:43:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:43:02 INFO - Python 2.7.3 20:43:02 INFO - Return code: 0 20:43:02 INFO - grabbing minidump binary from tooltool 20:43:02 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')]} 20:43:02 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 0x10d182d30>, '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 0x10cfa6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d179b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:43:02 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 20:43:02 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 20:43:02 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 20:43:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:43:02 INFO - Return code: 0 20:43:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:43:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:43:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:43:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:43:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:43:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:43:03 INFO - Return code: 0 20:43:03 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/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 20:43:03 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/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0257 --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 20:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.noPow6p16F/Render', 20:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:43:03 INFO - 'HOME': '/Users/cltbld', 20:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:43:03 INFO - 'LOGNAME': 'cltbld', 20:43:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:43:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:43:03 INFO - 'MOZ_NO_REMOTE': '1', 20:43:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:43:03 INFO - 'NO_EM_RESTART': '1', 20:43:03 INFO - 'PAGER': '/bin/cat', 20:43:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:43:03 INFO - 'PWD': '/builds/slave/test', 20:43:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:43:03 INFO - 'SHELL': '/bin/bash', 20:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qN2h9X77au/Listeners', 20:43:03 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 20:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:43:03 INFO - 'USER': 'cltbld', 20:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:43:03 INFO - 'XPC_FLAGS': '0x0', 20:43:03 INFO - 'XPC_SERVICE_NAME': '0', 20:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:43:03 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/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 20:43:04 INFO - mozversion application_buildid: 20160524194536 20:43:04 INFO - mozversion application_changeset: ea60f9e94a807a0be57df93c9716426b5651b21b 20:43:04 INFO - mozversion application_display_name: Nightly 20:43:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:43:04 INFO - mozversion application_name: Firefox 20:43:04 INFO - mozversion application_remotingname: firefox 20:43:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:43:04 INFO - mozversion application_vendor: Mozilla 20:43:04 INFO - mozversion application_version: 49.0a1 20:43:04 INFO - mozversion platform_buildid: 20160524194536 20:43:04 INFO - mozversion platform_changeset: ea60f9e94a807a0be57df93c9716426b5651b21b 20:43:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:43:04 INFO - mozversion platform_version: 49.0a1 20:43:04 INFO - using testdate: 1464147784 20:43:04 INFO - actual date: 1464147784 20:43:04 INFO - starting webserver on 'localhost:49278' 20:43:04 INFO - SUITE-START | Running 2 tests 20:43:04 INFO - TEST-START | dromaeo_css 20:43:04 INFO - operating with platform_type : mac_ 20:43:04 INFO - Initialising browser for dromaeo_css test... 20:43:04 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpLxdKLX/profile http://localhost:49278/getInfo.html) 20:43:06 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 20:43:06 INFO - PROCESS | 1672 | colorDepth:24 20:43:06 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 20:43:06 INFO - PROCESS | 1672 | __metrics 20:43:06 INFO - PROCESS | 1672 | 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" 20:43:06 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:43:07 INFO - TEST-INFO | 1672: exit 0 20:43:07 INFO - Browser initialized. 20:43:07 INFO - Running cycle 1/1 for dromaeo_css test... 20:43:07 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpLxdKLX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:43:19 INFO - PROCESS | 1674 | RSS: Main: 239140864 20:43:19 INFO - PROCESS | 1674 | 20:44:11 INFO - PROCESS | 1674 | 1464147851246 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] 20:46:21 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 20:46:22 INFO - PROCESS | 1674 | RSS: Main: 379834368 20:46:22 INFO - PROCESS | 1674 | 20:49:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 20:49:26 INFO - PROCESS | 1674 | RSS: Main: 371613696 20:49:26 INFO - PROCESS | 1674 | 20:52:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 20:52:29 INFO - PROCESS | 1674 | RSS: Main: 367640576 20:52:29 INFO - PROCESS | 1674 | 20:55:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 20:55:32 INFO - PROCESS | 1674 | RSS: Main: 367886336 20:55:32 INFO - PROCESS | 1674 | 20:58:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 20:58:36 INFO - PROCESS | 1674 | RSS: Main: 337276928 20:58:36 INFO - PROCESS | 1674 | 21:01:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 21:01:38 INFO - PROCESS | 1674 | RSS: Main: 447115264 21:01:38 INFO - PROCESS | 1674 | 21:01:38 INFO - PROCESS | 1674 | __start_tp_report 21:01:38 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 21:01:38 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 21:01:38 INFO - PROCESS | 1674 | |i|pagename|runs| 21:01:38 INFO - PROCESS | 1674 | |0;dojo.html;3482.2513159670675;3482.8084455354947;3511.59616644086;3524;3524.08373822806;5771.711414429277;5777.82666520004;5808.622439541437;5891.558133140021;5906.704664766766;5680.829575112753;5822.679752613604;5915.615484993473;5932.080527518231;5953.077273022677;5203.453637368059;5232.398274198459;5301.390340110879;5301.681899086047;5418.593605479605;5347.331583552056;5376.220448035033;5387.568994480433;5407.756650950717;5443.129099344106;5194.350706161748;5258;5259.658122222081;5315.627906046579;5338.759755810978;4352.630026447756;5220.530152286275;5240.79036838526;5266.262723218752;5290.074237008508;5208.322918020671;5216.556592689626;5224.164133738622;5275.60432967529;5338.893222135574;5589.441055894418;5685.971570142165;5748.770049198027;5752.712364381806;5774.076147816371;6233.3143354231;6264.310925798159;6286.277078135981;6329.177206963065;6342.302346741854;5425.565954723613;6283.120363149117;6307.526935479811;6311.810645680637;6360.7137678804565;5757.337906140764;5830.62100963436;5901.734421951022;5928.851778705567;5956.612820166719;6150.585365365991;6251.74993000284;6332.68336583169;6333.303336632966;6366.840828979312;5481.725913704299;6147.231596050494;6186.443220110212;6198.349173336735;6215.595986260879;6126.181801870944;6174.228221472316;6257.906131408033;6284.717187726564;6292.402221788922;6144.539159563051;6186.1648677428875;6225.3774622537385;6239.906401403982;6255.343188965184;6157.230346206724;6191.535634827406;6201.56589038772;6217.347178546188;6240.906386404116;4926.285688575208;4972.7513624318635;4974.5274198951765;5013.147764879979;5048.949510504848;3855.556610989716;4534.274516077412;4547.272436410225;4549.112922979987;4554.225781617133;5720.742566584514;5746.2529871116485;5746.569007324384;5750.626209296466;5754.539636829044;6190.3190649028575;6256;6310.116583678288;6314.684265786692;6327.145835312266;5688.601797874192;5756;5759.164921086419;5765.106408506688;5782;5351.759170837322;5445.782168713286;5457.481539253765;5472.699001554953;5474.042042642602;6298.11826344303;6356.459700925473;6366.299707032223;6399.392057754506;6476.060971159061;5161.406438259648;5992.8801423972145;6300.621962682254;6304.211973503312;6329.145565348619;6253.78111766086;6417.96791016042;6442.194725659293;6456.289808121009;6479.319671434431;5337.692265395003;5884.08796636522;6240.594361366497;6294.6852657366035;6299.275583307795;6314.842128946813;6319.58922670025;6322.2729386119345;6323.462505687068;6332.050192471166;5968.31364393083;6004.849878753066;6123.326434092335;6173.197484327009;6176.413240742122;6117.3270940197435;6155.938440615537;6156.138140660221;6164.291106522628;6172.537059720449;5922.289325280994;6022.277326720821;6023.849403764941;6037.969810151097;6101.969490152521;5963.91054134197;6105.217149441772;6138.388398386059;6161.229846269216;6184.226971628546;6123.5876691309595;6126.94520042658;6130.306415576139;6131.590761709946;6141.854248382413 21:01:38 INFO - PROCESS | 1674 | |1;ext.html;501.56113400774336;514.8095204774233;517.629894625343;527.4725274725274;530.6497711510403;32188.67811321889;45115.77442112789;46418;46570.534294657045;49364.753176234066;46457.30314045292;47020;47186;47210.29184562234;47407.76296118523;8643.913560864405;9660.27547933906;9881;9914.355566888129;9916.157126644244;11726.648200553997;12006.339651319175;12011.879881201163;12279.877201227962;12282.754344913097;11858.584949526768;11969.7606047879;11973.281603103842;11980.640580782547;11996.940015299911;8672.826543469095;11625.53497860093;11855.703607409798;11887.108466864976;12009.519619215222;11946.701332466671;12080.97311728504;12090.576829810914;12124.030077593772;12134.696632584257;43167.78416107899;43585.56414435847;43608.3458748119;43624.78187609073;43631.34552981708;2650.642163307948;3200.9839950800097;3249.992502324287;3250.6261779895376;3438.9312213755834;30658.54012189819;30698.846505767553;30851.382972340652;31020.22449438782;31392;2678.665166854143;2753.2979090332037;2764.350377661248;2783.053761721025;2865.7134286571386;512200.9999999963;512546;513029;514329;515224;35530.644693552735;36485.90542283736;65374.673126634065;65729;65825.67087164533;36482.08794780152;37916.43125353122;38122.23755524874;38286;38385.616143838764;14804.259787010606;15044.398224070914;15047.849521504864;15048.774268385983;15056.94601377893;11973.281603103842;12300.876991229974;12932.224066556037;13151.60545183646;13218.074734768474;8110.513369197867;8162.755117346489;8174.591270436455;8201.22088401601;8214.1375155609;3275.459549174407;3300.5709257796334;3302.5211344354934;3302.983485082559;3343.481760327153;987.2694206287296;1005.4972513743129;1020.5917632946881;1053.952572134256;1075.2741899218154;30251.394972100436;30362.089137325915;30730.924417645274;30814.67166082255;30848.84575577108;1076.5586109695143;1084.2356138922041;1097.0948967102172;1103.8178700514486;1118.0943435817014;13635.863641363658;15793.605159871095;16173.514794556182;16491.83508164927;16508.917455412648;702389;705953;708732;709436;709552;66871.66564167148;67219.6556068876;67237.32762672409;67251.66374168098;67482.66258668773;1286.6654669785923;1394.3516264936852;1431.0269017068285;1448.2469116059801;1448.7826825976188;24909;31210.531842022843;31484.68515314909;31677.524837127923;31837.522437163;56128.43871561396;56354;56995;57338;57387.71306143443;588853;591503;592723;599076;599436;23933.401664958514;26714.46571068529;27302.726972730812;27768.166954991382;28585.42829143447;47903.5209647899;48411.03177936351;48797;49309.75345123395;50262.7486862578;38235.61764382321;38695.80652096721;38887.6111238884;39352.60647393604;40368;31812.04563863088;38695.613043870326;38896.41655375115;38901.02747431337;39001.829945101694;38369;38807;39171.80414097911;39389.80305098456;39460.802695986335;33611.3277734439;34051.31897361989;34382;34576.654233457346;34756.9572912813;24881.377965551004;24963.251102466955;25357.228386684674;25495.872520637276;25607.97568097255 21:01:38 INFO - PROCESS | 1674 | |2;jquery.html;379.9164183879547;383.77740910272047;387.16758968218335;395.28453499166494;402.80463974972133;20093.39719808406;21497.355079347606;22191.44521386966;22640.66039009414;22676;21079.60932230912;21215.469613259676;21423.250186243484;21732;22158.556828863413;5798.188253644493;5949.613275137124;5975.402459754033;6129.264488261392;6156.5998210116195;4192.937105943398;4423.690341676084;6019.819405417845;6386.393292637192;6736.124303840494;5761.855953601152;6638.6348750818925;6712.194536655608;6739.561928474658;6743.7524058049175;6535.052417399451;6616.139901812758;6714.865702685967;6720.865582688343;6740.056392105133;3754.624537546251;5381.784728610851;5496.148097044965;5550.639208451437;5860.941390586082;26132.216033518835;26284.342891427677;26745.73254267452;26998.73001269982;27281;2030.8172264496118;2132.5948069866677;2199.1533259694975;2216.6785816056745;2302.435903203726;14868.851311486855;15077.321520531603;15149.318280677397;15169.544913652608;15602.921985390114;1571.6542360680633;1621.4000819696787;1689.493152054391;1745.4938067960277;1784.4735802938164;113801.43099284533;117295.99999999914;118326;118576;119336.20995684985;10718.89281107179;10962.890371096346;11267.042301404472;11273.379964102052;11390.829137562943;14273.072250303698;14321.427142914166;14358.784618230735;14365.497207597684;14367.568972930829;8633.870491942625;8850.645974160961;8862.158094980967;8926.509042002623;8948.73153805387;8841.55792210387;8868.206514947771;8878.467291962503;8970.82058358829;8986.820263594691;5510.1459273812625;6823.215330236987;6857.6740280963695;6863.6568171591225;6869.965650171717;1599.6240883392393;1667.7915260592426;1801.3605170164665;1846.2891786662233;1866.4400679796006;885.3713863157102;945.8912225094065;957;972.9270304727032;1001.6193846338344;20715.689264661043;21843.563128737336;21986;22035.228766993077;22051.33845984623;976.8339382305026;981.9950900245453;998.2463240253562;1009.3489699144011;1025.108155904353;11417;11668.649940501798;11720.355380454157;11722.58970936013;11786.528538858349;255717;256162.15756763663;590802;607820.9608951927;626228;94548;118261.40869295599;118519;119454;121748;1027.465717826726;1047.9685609431729;1050.6375300521308;1071.614218881202;1074.822654262038;19052.523686907385;19124;19230;19418.417447476597;19438.8056119437;47275.290870637655;48042.5195748052;49076.50923490719;49092.75453622709;49247.50752492573;71323.64338178276;71986;72011.91982120377;72830.27169728446;73091.63454182695;3970.642642162219;7864.129592698509;8103.638643664695;8317.500949943023;8344;8630.266427353745;10844;12831.165974211646;13040.54358097462;13429.194248345131;8622.82754344922;10798;10839.566417343218;11044.392558409365;11247.38139402308;9981.950090249502;10381.792364152523;10918.454077296263;11036.944815275872;11178.496967636313;11106.888931110585;11325.207235493437;11348.773024539629;11629.825552616549;11667.883321166679;6942.930570694431;8083.8383232333845;8183.058948221031;8251.885995390545;8439.071702112886;9108.817823643623;9296.581653825458;11307.491162897853;11316.490757916079;11524.539018439169 21:01:38 INFO - PROCESS | 1674 | |3;mootools.html;1417.5392997275887;1563.1558958162595;1592.8168260650025;1840.825121613447;1977.2189984955942;7845.843083138334;8673.522956237412;8920.464772113677;8921.01868794432;9057.184853363196;7275.417966562675;8684.913150868506;8728.956355218232;8894;8905.866412003808;2095.4132842803965;2315.6294992801154;2357.5874222011166;2398.20859116492;2400.603900356439;2399.0883464283547;2420.3586049696846;2421.1889017179265;2426.284246147382;2428;2356.74075851657;2360.9291721248305;2389.199618127929;2399.4601214726636;2404.879756012201;2019.5254115282896;2041.8468614853946;2071.720317757106;2292.266474728088;2334.8365614407;2378.8691621960784;2379.785819276256;2404.4349577849193;2404.675368825221;2405.3625789165885;8738.60676269563;8815.823683526294;8843.292536597057;8919.018907920123;8919.375643704943;5256.237845512379;5407.621056630574;5419.132938729824;5425.104857698475;5455.427180146107;3357.5635167428322;3411.198368383428;3576.266865292635;3578.159132603836;3583.122135076897;1040.5369610523321;1045.090771029202;1047.4291511126455;1048.26621365044;1064.4305296666246;11781.999999999915;11816.763664726743;11818.409079545989;11822.113341499336;11855.229410088317;4599.816007359668;4660.97669511657;4761.142994260997;4767.4517430495935;4787.640926930494;9946.850797238047;10001.34991225568;10022.949885250673;10038.698839034842;10060.094591486655;5354.116570765858;5361.839144825662;5385.7307134643115;5387.622866399314;5388.084025715638;4320.546342634041;4478.395416618715;4778.04439112179;4919.114559379346;4923.630727695366;3902.9852238417648;3967.603239676067;3989.3218152914073;3996.760194388346;4035.1526179502634;1287.1183239480986;1291.7416516696699;1299.3368488334947;1308.0908768405866;1308.3196737696344;890.0476490155601;900.0054939291988;905;916.6241840845222;950.4500049451175;5889.352171261158;5894.911576326359;5948.345681974979;6027;6096.268447786294;724.3625609463638;887.9866801997975;892.5358813416987;896.3994123936978;920.1442658327819;3773.509443772347;3833.2333533293454;3879.2435475082652;3948.309045916953;3983.083890705154;10628.89371106279;10658.73353166177;10748.355098694103;10922.781544369227;11068.944655276671;9221.53892305382;10693.9465302673;10928.01647851697;11010.944945275221;11027.283226590245;7943.563104029218;8187.79530511742;8317.792055198548;8337.207965243293;8433.915660843313;5220.216967454912;5659.462351076641;5881.882362352643;5907.2911250650195;5988.5508586855285;9738.75653108678;9825.852612210965;9839.655612053544;9851.556679949275;10079.143272821599;9865.753356166153;10176.287659863738;10244.897551024693;10394.376337419779;10437.32157409766;6822.385985261152;6958.617276049866;7163.3552980232025;7552.66013029404;7727.961360193163;8294.87557686623;8388.286995605442;8412.537310447984;8470.57647117654;8481.321494280319;7318.048653674989;7325.194228634739;7440.218777028334;7534.0582427196605;7589.962050189713;7235.059442272682;7277.38142257914;7331.193568707545;7348.632568371667;7374.741884034033;6972;7003.894941575779;7039.577625342496;7040.3311685391855;7057.082579264663;4524.117797029548;4584.908301834012;4704.882377940579;4887.36464259644;4906.141425250638;4077.6737860970456;4585.793639286173;4721.952780472288;4748.762561871962;4980.651354405301 21:01:38 INFO - PROCESS | 1674 | |4;prototype.html;275.7366714787378;314.92284390324374;318.43001028159597;321.33644025088194;324.58939441606356;4408.977955110228;4925.754071913414;5008.068666097114;5351.625386222966;5403.432639572848;4590.19671557477;5125.8462246132585;5353.357597088345;5378.273933019042;5418.130744893016;3345.8159801211045;3866.845326186949;3927.666148377377;4085.0391811930144;4089.9386509202386;4066.5323487798987;4102.04652650093;4116.814743336542;4128.60778226068;4130.566290539495;4094.5905409458965;4105.692073094515;4105.7331273467125;4110.815013324393;4118.19695159444;3523.612402635708;3919.902002449933;4096.201240758053;4106.7125301228925;4120.958790412087;3281.885134020322;3299.340131973591;3387.0516255448515;3576.928461430783;3597.9820100899587;4733.384659994179;4733.952660473386;5195.402528709209;5200.737679109561;5218.712970786605;3878.4182372644045;4691.835785747482;4763.237881938873;4763.809447622091;4802.951970480285;4812.350332705075;4850.5877000455;5002.97439024999;5665.8668266346895;5675.404082571353;3403.6936675699308;3846.4614953906475;3873.786941718204;3992.5807790181902;3998.10042740384;5286.524212820865;5368.033753924292;5669.461401166924;5670.461306175948;5992.704012792947;3434.6050204226835;3582.6417358263966;4024.2353952749468;4067.918641627151;4072;3623.9094022649647;4049.088954985105;4118.485189351331;4135.028268356934;4161.210679407824;4110.23960567296;4459.531749166355;4793.832215872428;4825.372701548847;4832.299316599074;5642.037930086737;5646.830595082154;5728.51307638847;5734.225879506297;5794.130880367894;1223.3088305107585;1395.8902672375486;1420.8650178233052;1421.630376102221;1431.9475185738474;867.6659486097898;874.7813046738315;876.1151237250295;928.7306681062552;944.9086305317369;5238.869028274324;5292.603054770908;5301.814436494704;5304.761285742151;5309.212663351694;5433.646812957145;5661.924234395452;5689.345725241567;5794.82615521535;5805;4935.950640493621;5262.736863156827;5264.736763161826;5377.5160235578205;5387.24578559002;2631.7236690147643;2838.9858050710027;3040.2855328997666;3057.3579548295106;3099.302656902179;4994.700317980933;5941.58408911372;6113.266408030976;6123.203983482208;6133.221365803956;5335.718533102192;5985.815177460283;5992.250968628921;6002.759889604455;6014.774533422574;5326.840194794162;6123.081228014074;6123.663198524124;6135.785247516315;6139.723712432862;5119.974400127976;5286.91618218272;5858.326292476419;5897.365463853165;5899.764009439746;4777.307290442797;5704.486596206195;5762.567807414377;5792.9710351447975;5796.739146738491;5682.130524085311;6112.908306375319;6114.296855861633;6118.357572455006;6121.969390153199;3148.9055328340187;3353.9161520961197;3504.159001839591;3579.6599323064265;3904.4143378492317;4973.438679080205;5352.919706204488;5542.61201715876;5802.680852552981;5821.893840170354;4846.321514987834;5439.646422982493;5681.119426488901;5708.372079071381;5708.885822283448;4621.953780462152;4996.275540046746;5203.282916637576;5438.211459338453;5657.010023245998;4606;5015.97172579613;5198;5252.579793616425;5476.12382018891;4762.476127625889;5200.855811721415;5262.736863156751;5265.341832270966;5653.943460565341;5074.07814420012;5516.724163791875;5554.777808887599;5564.582656300713;5581.358143813513 21:01:38 INFO - PROCESS | 1674 | |5;yui.html;602.8281939647198;635.8823617630738;726.201178703426;733.6955163607104;739.8853177757446;14921.328540215689;14935.626609334771;15219.391224351026;15442;15495.302711377986;14019.719605607881;14095.78856317156;14166.78749818751;14171.149731016147;14172.50396236132;718.5078221418319;741.3031750154864;889.4574309671095;894.1773568317147;912.4935660708304;897.8787863638423;900.3967341880956;902.2917010147036;902.4404868981208;904.3895370624834;938.9295802814783;948.7580666929923;950.1116456113522;952.014664821906;952.8475443928972;748.7267147491181;918.3709159225884;936.0171819589438;943.4905151218403;949.3449519831295;700.7197121151529;702.596007295807;702.6592102830124;703.4231929817552;706.0574133531742;14715.852841471555;14725.631859203499;14728.116313021254;14732.410703571844;14744.820414366824;12124.636260912186;12196.14626976112;12199.512019519208;12456.252624842447;12463;10819.296745711503;10910.963458471433;10998.955099265557;11346.319220846775;11546.769064618744;3971.146203566247;3978.9403158952637;3978.980105099456;4063.878083657495;4163.250614889319;82625;83258.58370708168;83273.75089373664;84081.57959210165;84086;9411.341206115643;9414.529273536296;9508.049195080575;9662.067586482592;9790.608375665037;20538;21465.67801482979;21535.784642153692;21922.780772192073;21933.890330548566;7074;7271.527350722186;7431.29402706742;7568.394528437711;7741.148473667892;3682.1879522882455;3691.3724666806706;3855.1291428458103;3941.881743547641;3954.5649978502342;3264.1186879542392;3337.6495467975997;3358.378699940519;3358.462645976632;3361.865525379006;560.4675558219708;593.6853467662146;605.3613437823051;608.2427377914437;618.4125081172814;465.57399979019493;499.7251511668568;538.7064050092708;577.4023885278716;577.5610535992676;8100.554469504116;8101.270885620323;8176.509409435453;8189.508629482131;8270.917290827014;465.8206590462696;515.6622412319937;592.0408937521244;597.5876645114856;615.2340336281359;3820.7707537547835;3917.9020524487114;3968.7023473239046;3994.9201015979515;4070.841152035152;88640;91863;92775;93358;93536;74254.62872685603;77388;77702.83445748319;81492.59253703694;83282.33435331381;55500;66098.34754131186;66341;67623.66188169028;68292.31707682958;8137.79655508617;8169.754907352789;8171.591420428836;8255.834883302421;8308.83382332362;42324.57675423206;42731.786341068095;42870.78564607157;42888;43284.13431731285;55090;57398.85202296014;59832;60436.697816510634;60493;10645.627403040857;10943.616973405893;11091.057260132979;11196.104311654884;11601.419929003347;11365.829512557153;12067.396630168632;12131.09016823724;12184.75630487403;12324.927172701895;9655.710328690151;11128.554857805597;11370.260933039657;11812.76374472523;12108.031357491203;11426.828597570877;11459.942700286445;11699;11764.35296058725;11855.110866685205;11190.272632279202;11196.720081998015;11459.942700286445;11470.391752166233;11600.30398176112;9358.672446464341;9358.812823743623;9522.619095236112;9708.75728106803;10021.799564008825;7684.193159718149;7816.6091695416135;8609.870851937101;8930.464172149692;9554.283428742732 21:01:38 INFO - PROCESS | 1674 | __end_tp_report 21:01:38 INFO - PROCESS | 1674 | __start_cc_report 21:01:38 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1397 21:01:38 INFO - PROCESS | 1674 | __end_cc_report 21:01:38 INFO - PROCESS | 1674 | __startTimestamp1464148898981__endTimestamp 21:01:38 INFO - PROCESS | 1674 | 21:01:38 INFO - PROCESS | 1674 | ------- Summary: start ------- 21:01:38 INFO - PROCESS | 1674 | Number of tests: 6 21:01:38 INFO - PROCESS | 1674 | 21:01:38 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5779.46 Median:5968.31 stddev:636.75 (10.7%) stddev-sans-first:612.68 21:01:38 INFO - PROCESS | 1674 | Values: 3482.3 3482.8 3511.6 3524.0 3524.1 5771.7 5777.8 5808.6 5891.6 5906.7 5680.8 5822.7 5915.6 5932.1 5953.1 5203.5 5232.4 5301.4 5301.7 5418.6 5347.3 5376.2 5387.6 5407.8 5443.1 5194.4 5258.0 5259.7 5315.6 5338.8 4352.6 5220.5 5240.8 5266.3 5290.1 5208.3 5216.6 5224.2 5275.6 5338.9 5589.4 5686.0 5748.8 5752.7 5774.1 6233.3 6264.3 6286.3 6329.2 6342.3 5425.6 6283.1 6307.5 6311.8 6360.7 5757.3 5830.6 5901.7 5928.9 5956.6 6150.6 6251.7 6332.7 6333.3 6366.8 5481.7 6147.2 6186.4 6198.3 6215.6 6126.2 6174.2 6257.9 6284.7 6292.4 6144.5 6186.2 6225.4 6239.9 6255.3 6157.2 6191.5 6201.6 6217.3 6240.9 4926.3 4972.8 4974.5 5013.1 5048.9 3855.6 4534.3 4547.3 4549.1 4554.2 5720.7 5746.3 5746.6 5750.6 5754.5 6190.3 6256.0 6310.1 6314.7 6327.1 5688.6 5756.0 5759.2 5765.1 5782.0 5351.8 5445.8 5457.5 5472.7 5474.0 6298.1 6356.5 6366.3 6399.4 6476.1 5161.4 5992.9 6300.6 6304.2 6329.1 6253.8 6418.0 6442.2 6456.3 6479.3 5337.7 5884.1 6240.6 6294.7 6299.3 6314.8 6319.6 6322.3 6323.5 6332.1 5968.3 6004.8 6123.3 6173.2 6176.4 6117.3 6155.9 6156.1 6164.3 6172.5 5922.3 6022.3 6023.8 6038.0 6102.0 5963.9 6105.2 6138.4 6161.2 6184.2 6123.6 6126.9 6130.3 6131.6 6141.9 21:01:38 INFO - PROCESS | 1674 | 21:01:38 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:73046.49 Median:18745.13 stddev:163491.06 (872.2%) stddev-sans-first:163858.97 21:01:38 INFO - PROCESS | 1674 | Values: 501.6 514.8 517.6 527.5 530.6 32188.7 45115.8 46418.0 46570.5 49364.8 46457.3 47020.0 47186.0 47210.3 47407.8 8643.9 9660.3 9881.0 9914.4 9916.2 11726.6 12006.3 12011.9 12279.9 12282.8 11858.6 11969.8 11973.3 11980.6 11996.9 8672.8 11625.5 11855.7 11887.1 12009.5 11946.7 12081.0 12090.6 12124.0 12134.7 43167.8 43585.6 43608.3 43624.8 43631.3 2650.6 3201.0 3250.0 3250.6 3438.9 30658.5 30698.8 30851.4 31020.2 31392.0 2678.7 2753.3 2764.4 2783.1 2865.7 512201.0 512546.0 513029.0 514329.0 515224.0 35530.6 36485.9 65374.7 65729.0 65825.7 36482.1 37916.4 38122.2 38286.0 38385.6 14804.3 15044.4 15047.8 15048.8 15056.9 11973.3 12300.9 12932.2 13151.6 13218.1 8110.5 8162.8 8174.6 8201.2 8214.1 3275.5 3300.6 3302.5 3303.0 3343.5 987.3 1005.5 1020.6 1054.0 1075.3 30251.4 30362.1 30730.9 30814.7 30848.8 1076.6 1084.2 1097.1 1103.8 1118.1 13635.9 15793.6 16173.5 16491.8 16508.9 702389.0 705953.0 708732.0 709436.0 709552.0 66871.7 67219.7 67237.3 67251.7 67482.7 1286.7 1394.4 1431.0 1448.2 1448.8 24909.0 31210.5 31484.7 31677.5 31837.5 56128.4 56354.0 56995.0 57338.0 57387.7 588853.0 591503.0 592723.0 599076.0 599436.0 23933.4 26714.5 27302.7 27768.2 28585.4 47903.5 48411.0 48797.0 49309.8 50262.7 38235.6 38695.8 38887.6 39352.6 40368.0 31812.0 38695.6 38896.4 38901.0 39001.8 38369.0 38807.0 39171.8 39389.8 39460.8 33611.3 34051.3 34382.0 34576.7 34757.0 24881.4 24963.3 25357.2 25495.9 25608.0 21:01:38 INFO - PROCESS | 1674 | 21:01:38 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:31025.22 Median:12489.22 stddev:83997.97 (672.6%) stddev-sans-first:84202.09 21:01:39 INFO - PROCESS | 1674 | Values: 379.9 383.8 387.2 395.3 402.8 20093.4 21497.4 22191.4 22640.7 22676.0 21079.6 21215.5 21423.3 21732.0 22158.6 5798.2 5949.6 5975.4 6129.3 6156.6 4192.9 4423.7 6019.8 6386.4 6736.1 5761.9 6638.6 6712.2 6739.6 6743.8 6535.1 6616.1 6714.9 6720.9 6740.1 3754.6 5381.8 5496.1 5550.6 5860.9 26132.2 26284.3 26745.7 26998.7 27281.0 2030.8 2132.6 2199.2 2216.7 2302.4 14868.9 15077.3 15149.3 15169.5 15602.9 1571.7 1621.4 1689.5 1745.5 1784.5 113801.4 117296.0 118326.0 118576.0 119336.2 10718.9 10962.9 11267.0 11273.4 11390.8 14273.1 14321.4 14358.8 14365.5 14367.6 8633.9 8850.6 8862.2 8926.5 8948.7 8841.6 8868.2 8878.5 8970.8 8986.8 5510.1 6823.2 6857.7 6863.7 6870.0 1599.6 1667.8 1801.4 1846.3 1866.4 885.4 945.9 957.0 972.9 1001.6 20715.7 21843.6 21986.0 22035.2 22051.3 976.8 982.0 998.2 1009.3 1025.1 11417.0 11668.6 11720.4 11722.6 11786.5 255717.0 256162.2 590802.0 607821.0 626228.0 94548.0 118261.4 118519.0 119454.0 121748.0 1027.5 1048.0 1050.6 1071.6 1074.8 19052.5 19124.0 19230.0 19418.4 19438.8 47275.3 48042.5 49076.5 49092.8 49247.5 71323.6 71986.0 72011.9 72830.3 73091.6 3970.6 7864.1 8103.6 8317.5 8344.0 8630.3 10844.0 12831.2 13040.5 13429.2 8622.8 10798.0 10839.6 11044.4 11247.4 9982.0 10381.8 10918.5 11036.9 11178.5 11106.9 11325.2 11348.8 11629.8 11667.9 6942.9 8083.8 8183.1 8251.9 8439.1 9108.8 9296.6 11307.5 11316.5 11524.5 21:01:39 INFO - PROCESS | 1674 | 21:01:39 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5695.90 Median:4772.75 stddev:3220.63 (67.5%) stddev-sans-first:3213.61 21:01:39 INFO - PROCESS | 1674 | Values: 1417.5 1563.2 1592.8 1840.8 1977.2 7845.8 8673.5 8920.5 8921.0 9057.2 7275.4 8684.9 8729.0 8894.0 8905.9 2095.4 2315.6 2357.6 2398.2 2400.6 2399.1 2420.4 2421.2 2426.3 2428.0 2356.7 2360.9 2389.2 2399.5 2404.9 2019.5 2041.8 2071.7 2292.3 2334.8 2378.9 2379.8 2404.4 2404.7 2405.4 8738.6 8815.8 8843.3 8919.0 8919.4 5256.2 5407.6 5419.1 5425.1 5455.4 3357.6 3411.2 3576.3 3578.2 3583.1 1040.5 1045.1 1047.4 1048.3 1064.4 11782.0 11816.8 11818.4 11822.1 11855.2 4599.8 4661.0 4761.1 4767.5 4787.6 9946.9 10001.3 10022.9 10038.7 10060.1 5354.1 5361.8 5385.7 5387.6 5388.1 4320.5 4478.4 4778.0 4919.1 4923.6 3903.0 3967.6 3989.3 3996.8 4035.2 1287.1 1291.7 1299.3 1308.1 1308.3 890.0 900.0 905.0 916.6 950.5 5889.4 5894.9 5948.3 6027.0 6096.3 724.4 888.0 892.5 896.4 920.1 3773.5 3833.2 3879.2 3948.3 3983.1 10628.9 10658.7 10748.4 10922.8 11068.9 9221.5 10693.9 10928.0 11010.9 11027.3 7943.6 8187.8 8317.8 8337.2 8433.9 5220.2 5659.5 5881.9 5907.3 5988.6 9738.8 9825.9 9839.7 9851.6 10079.1 9865.8 10176.3 10244.9 10394.4 10437.3 6822.4 6958.6 7163.4 7552.7 7728.0 8294.9 8388.3 8412.5 8470.6 8481.3 7318.0 7325.2 7440.2 7534.1 7590.0 7235.1 7277.4 7331.2 7348.6 7374.7 6972.0 7003.9 7039.6 7040.3 7057.1 4524.1 4584.9 4704.9 4887.4 4906.1 4077.7 4585.8 4722.0 4748.8 4980.7 21:01:39 INFO - PROCESS | 1674 | 21:01:39 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4559.27 Median:5122.91 stddev:1406.39 (27.5%) stddev-sans-first:1373.09 21:01:39 INFO - PROCESS | 1674 | Values: 275.7 314.9 318.4 321.3 324.6 4409.0 4925.8 5008.1 5351.6 5403.4 4590.2 5125.8 5353.4 5378.3 5418.1 3345.8 3866.8 3927.7 4085.0 4089.9 4066.5 4102.0 4116.8 4128.6 4130.6 4094.6 4105.7 4105.7 4110.8 4118.2 3523.6 3919.9 4096.2 4106.7 4121.0 3281.9 3299.3 3387.1 3576.9 3598.0 4733.4 4734.0 5195.4 5200.7 5218.7 3878.4 4691.8 4763.2 4763.8 4803.0 4812.4 4850.6 5003.0 5665.9 5675.4 3403.7 3846.5 3873.8 3992.6 3998.1 5286.5 5368.0 5669.5 5670.5 5992.7 3434.6 3582.6 4024.2 4067.9 4072.0 3623.9 4049.1 4118.5 4135.0 4161.2 4110.2 4459.5 4793.8 4825.4 4832.3 5642.0 5646.8 5728.5 5734.2 5794.1 1223.3 1395.9 1420.9 1421.6 1431.9 867.7 874.8 876.1 928.7 944.9 5238.9 5292.6 5301.8 5304.8 5309.2 5433.6 5661.9 5689.3 5794.8 5805.0 4936.0 5262.7 5264.7 5377.5 5387.2 2631.7 2839.0 3040.3 3057.4 3099.3 4994.7 5941.6 6113.3 6123.2 6133.2 5335.7 5985.8 5992.3 6002.8 6014.8 5326.8 6123.1 6123.7 6135.8 6139.7 5120.0 5286.9 5858.3 5897.4 5899.8 4777.3 5704.5 5762.6 5793.0 5796.7 5682.1 6112.9 6114.3 6118.4 6122.0 3148.9 3353.9 3504.2 3579.7 3904.4 4973.4 5352.9 5542.6 5802.7 5821.9 4846.3 5439.6 5681.1 5708.4 5708.9 4622.0 4996.3 5203.3 5438.2 5657.0 4606.0 5016.0 5198.0 5252.6 5476.1 4762.5 5200.9 5262.7 5265.3 5653.9 5074.1 5516.7 5554.8 5564.6 5581.4 21:01:39 INFO - PROCESS | 1674 | 21:01:39 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:17781.45 Median:584.80 stddev:24974.07 (4270.5%) stddev-sans-first:25010.81 21:01:39 INFO - PROCESS | 1674 | Values: 602.8 635.9 726.2 733.7 739.9 14921.3 14935.6 15219.4 15442.0 15495.3 14019.7 14095.8 14166.8 14171.1 14172.5 718.5 741.3 889.5 894.2 912.5 897.9 900.4 902.3 902.4 904.4 938.9 948.8 950.1 952.0 952.8 748.7 918.4 936.0 943.5 949.3 700.7 702.6 702.7 703.4 706.1 14715.9 14725.6 14728.1 14732.4 14744.8 12124.6 12196.1 12199.5 12456.3 12463.0 10819.3 10911.0 10999.0 11346.3 11546.8 3971.1 3978.9 3979.0 4063.9 4163.3 82625.0 83258.6 83273.8 84081.6 84086.0 9411.3 9414.5 9508.0 9662.1 9790.6 20538.0 21465.7 21535.8 21922.8 21933.9 7074.0 7271.5 7431.3 7568.4 7741.1 3682.2 3691.4 3855.1 3941.9 3954.6 3264.1 3337.6 3358.4 3358.5 3361.9 560.5 593.7 605.4 608.2 618.4 465.6 499.7 538.7 577.4 577.6 8100.6 8101.3 8176.5 8189.5 8270.9 465.8 515.7 592.0 597.6 615.2 3820.8 3917.9 3968.7 3994.9 4070.8 88640.0 91863.0 92775.0 93358.0 93536.0 74254.6 77388.0 77702.8 81492.6 83282.3 55500.0 66098.3 66341.0 67623.7 68292.3 8137.8 8169.8 8171.6 8255.8 8308.8 42324.6 42731.8 42870.8 42888.0 43284.1 55090.0 57398.9 59832.0 60436.7 60493.0 10645.6 10943.6 11091.1 11196.1 11601.4 11365.8 12067.4 12131.1 12184.8 12324.9 9655.7 11128.6 11370.3 11812.8 12108.0 11426.8 11459.9 11699.0 11764.4 11855.1 11190.3 11196.7 11459.9 11470.4 11600.3 9358.7 9358.8 9522.6 9708.8 10021.8 7684.2 7816.6 8609.9 8930.5 9554.3 21:01:39 INFO - PROCESS | 1674 | -------- Summary: end -------- 21:01:39 INFO - PROCESS | 1674 | 21:01:39 INFO - TEST-INFO | 1674: exit 0 21:01:39 INFO - TEST-OK | dromaeo_css | took 1115562ms 21:01:39 INFO - TEST-START | kraken 21:01:39 INFO - operating with platform_type : mac_ 21:01:39 INFO - Initialising browser for kraken test... 21:01:39 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmp3VgR3d/profile http://localhost:49278/getInfo.html) 21:01:41 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 21:01:41 INFO - PROCESS | 1683 | colorDepth:24 21:01:41 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 21:01:41 INFO - PROCESS | 1683 | __metrics 21:01:41 INFO - PROCESS | 1683 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:01:41 ERROR - PROCESS | 1683 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:01:42 INFO - TEST-INFO | 1683: exit 0 21:01:42 INFO - Browser initialized. 21:01:42 INFO - Running cycle 1/1 for kraken test... 21:01:42 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmp3VgR3d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:01:54 INFO - PROCESS | 1685 | RSS: Main: 239190016 21:01:54 INFO - PROCESS | 1685 | 21:01:57 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 21:01:58 INFO - PROCESS | 1685 | RSS: Main: 286674944 21:01:58 INFO - PROCESS | 1685 | 21:02:01 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 21:02:01 INFO - PROCESS | 1685 | RSS: Main: 295497728 21:02:01 INFO - PROCESS | 1685 | 21:02:05 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 21:02:05 INFO - PROCESS | 1685 | RSS: Main: 387100672 21:02:05 INFO - PROCESS | 1685 | 21:02:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 21:02:08 INFO - PROCESS | 1685 | RSS: Main: 285011968 21:02:08 INFO - PROCESS | 1685 | 21:02:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:02:11 INFO - PROCESS | 1685 | RSS: Main: 288956416 21:02:11 INFO - PROCESS | 1685 | 21:02:14 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 21:02:14 INFO - PROCESS | 1685 | RSS: Main: 325844992 21:02:14 INFO - PROCESS | 1685 | 21:02:17 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 21:02:18 INFO - PROCESS | 1685 | RSS: Main: 328278016 21:02:18 INFO - PROCESS | 1685 | 21:02:21 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 21:02:21 INFO - PROCESS | 1685 | RSS: Main: 325419008 21:02:21 INFO - PROCESS | 1685 | 21:02:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:02:23 INFO - PROCESS | 1685 | RSS: Main: 313069568 21:02:23 INFO - PROCESS | 1685 | 21:02:25 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:02:26 INFO - PROCESS | 1685 | RSS: Main: 320577536 21:02:26 INFO - PROCESS | 1685 | 21:02:28 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:02:29 INFO - PROCESS | 1685 | RSS: Main: 321695744 21:02:29 INFO - PROCESS | 1685 | 21:02:32 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:02:32 INFO - PROCESS | 1685 | RSS: Main: 326213632 21:02:32 INFO - PROCESS | 1685 | 21:02:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:02:36 INFO - PROCESS | 1685 | RSS: Main: 324042752 21:02:36 INFO - PROCESS | 1685 | 21:02:37 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 21:02:38 INFO - PROCESS | 1685 | RSS: Main: 371048448 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | __start_tp_report 21:02:38 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 21:02:38 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 21:02:38 INFO - PROCESS | 1685 | |i|pagename|runs| 21:02:38 INFO - PROCESS | 1685 | |0;ai-astar;112;104;112;113;160;89;165;165;167;97 21:02:38 INFO - PROCESS | 1685 | |1;audio-beat-detection;153;122;116;165;117;118;159;141;180;111 21:02:38 INFO - PROCESS | 1685 | |2;audio-dft;183;184;185;182;184;183;183;180;184;183 21:02:38 INFO - PROCESS | 1685 | |3;audio-fft;77;57;61;108;82;80;60;54;95;56 21:02:38 INFO - PROCESS | 1685 | |4;audio-oscillator;90;69;98;71;77;69;93;86;100;136 21:02:38 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;88;90;135;72;134;130;102;86;88;85 21:02:38 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;93;93;94;93;95;96;94;93;93 21:02:38 INFO - PROCESS | 1685 | |7;imaging-desaturate;85;83;86;86;85;84;84;85;91;131 21:02:38 INFO - PROCESS | 1685 | |8;json-parse-financial;59;54;56;55;54;56;54;76;56;54 21:02:38 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;65;68;81;78;71;71;69;70;69;71 21:02:38 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;85;86;86;84;82;84;88;107;108;81 21:02:38 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;157;149;142;138;135;138;136;134;184;125 21:02:38 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;165;165;166;161;160;161;162;161;162;162 21:02:38 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;68;66;66;65;65;64;66;67;65;64 21:02:38 INFO - PROCESS | 1685 | __end_tp_report 21:02:38 INFO - PROCESS | 1685 | __start_cc_report 21:02:38 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,2138 21:02:38 INFO - PROCESS | 1685 | __end_cc_report 21:02:38 INFO - PROCESS | 1685 | __startTimestamp1464148958173__endTimestamp 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | ------- Summary: start ------- 21:02:38 INFO - PROCESS | 1685 | Number of tests: 14 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:128.40 Median:165.00 stddev:31.76 (19.2%) stddev-sans-first:33.12 21:02:38 INFO - PROCESS | 1685 | Values: 112.0 104.0 112.0 113.0 160.0 89.0 165.0 165.0 167.0 97.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:147.00 stddev:24.67 (16.8%) stddev-sans-first:25.58 21:02:38 INFO - PROCESS | 1685 | Values: 153.0 122.0 116.0 165.0 117.0 118.0 159.0 141.0 180.0 111.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:1.37 (0.7%) stddev-sans-first:1.45 21:02:38 INFO - PROCESS | 1685 | Values: 183.0 184.0 185.0 182.0 184.0 183.0 183.0 180.0 184.0 183.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:73.00 Median:69.00 stddev:18.48 (26.8%) stddev-sans-first:19.55 21:02:38 INFO - PROCESS | 1685 | Values: 77.0 57.0 61.0 108.0 82.0 80.0 60.0 54.0 95.0 56.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:81.50 stddev:20.29 (24.9%) stddev-sans-first:21.52 21:02:38 INFO - PROCESS | 1685 | Values: 90.0 69.0 98.0 71.0 77.0 69.0 93.0 86.0 100.0 136.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:101.00 Median:85.50 stddev:23.26 (27.2%) stddev-sans-first:24.19 21:02:38 INFO - PROCESS | 1685 | Values: 88.0 90.0 135.0 72.0 134.0 130.0 102.0 86.0 88.0 85.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:1.10 (1.2%) stddev-sans-first:1.09 21:02:38 INFO - PROCESS | 1685 | Values: 95.0 93.0 93.0 94.0 93.0 95.0 96.0 94.0 93.0 93.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:90.00 Median:85.00 stddev:14.57 (17.1%) stddev-sans-first:15.34 21:02:38 INFO - PROCESS | 1685 | Values: 85.0 83.0 86.0 86.0 85.0 84.0 84.0 85.0 91.0 131.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:56.00 stddev:6.72 (12.0%) stddev-sans-first:7.10 21:02:38 INFO - PROCESS | 1685 | Values: 59.0 54.0 56.0 55.0 54.0 56.0 54.0 76.0 56.0 54.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:4.74 (6.7%) stddev-sans-first:4.44 21:02:38 INFO - PROCESS | 1685 | Values: 65.0 68.0 81.0 78.0 71.0 71.0 69.0 70.0 69.0 71.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:84.50 stddev:9.90 (11.7%) stddev-sans-first:10.39 21:02:38 INFO - PROCESS | 1685 | Values: 85.0 86.0 86.0 84.0 82.0 84.0 88.0 107.0 108.0 81.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:140.00 stddev:16.59 (11.8%) stddev-sans-first:16.89 21:02:38 INFO - PROCESS | 1685 | Values: 157.0 149.0 142.0 138.0 135.0 138.0 136.0 134.0 184.0 125.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.00 stddev:2.07 (1.3%) stddev-sans-first:1.99 21:02:38 INFO - PROCESS | 1685 | Values: 165.0 165.0 166.0 161.0 160.0 161.0 162.0 161.0 162.0 162.0 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.26 (1.9%) stddev-sans-first:1.00 21:02:38 INFO - PROCESS | 1685 | Values: 68.0 66.0 66.0 65.0 65.0 64.0 66.0 67.0 65.0 64.0 21:02:38 INFO - PROCESS | 1685 | -------- Summary: end -------- 21:02:38 INFO - PROCESS | 1685 | 21:02:38 INFO - TEST-INFO | 1685: exit 0 21:02:38 INFO - TEST-OK | kraken | took 59270ms 21:02:38 INFO - SUITE-END | took 1174s 21:02:39 INFO - Completed test suite (00:19:35) 21:02:39 INFO - javascript benchmark 21:02:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3482.2513159670675, 3482.8084455354947, 3511.59616644086, 3524.0, 3524.08373822806, 5771.711414429277, 5777.82666520004, 5808.622439541437, 5891.558133140021, 5906.704664766766, 5680.829575112753, 5822.679752613604, 5915.615484993473, 5932.080527518231, 5953.077273022677, 5203.453637368059, 5232.398274198459, 5301.390340110879, 5301.681899086047, 5418.593605479605, 5347.331583552056, 5376.220448035033, 5387.568994480433, 5407.756650950717, 5443.129099344106, 5194.350706161748, 5258.0, 5259.658122222081, 5315.627906046579, 5338.759755810978, 4352.630026447756, 5220.530152286275, 5240.79036838526, 5266.262723218752, 5290.074237008508, 5208.322918020671, 5216.556592689626, 5224.164133738622, 5275.60432967529, 5338.893222135574, 5589.441055894418, 5685.971570142165, 5748.770049198027, 5752.712364381806, 5774.076147816371, 6233.3143354231, 6264.310925798159, 6286.277078135981, 6329.177206963065, 6342.302346741854, 5425.565954723613, 6283.120363149117, 6307.526935479811, 6311.810645680637, 6360.7137678804565, 5757.337906140764, 5830.62100963436, 5901.734421951022, 5928.851778705567, 5956.612820166719, 6150.585365365991, 6251.74993000284, 6332.68336583169, 6333.303336632966, 6366.840828979312, 5481.725913704299, 6147.231596050494, 6186.443220110212, 6198.349173336735, 6215.595986260879, 6126.181801870944, 6174.228221472316, 6257.906131408033, 6284.717187726564, 6292.402221788922, 6144.539159563051, 6186.1648677428875, 6225.3774622537385, 6239.906401403982, 6255.343188965184, 6157.230346206724, 6191.535634827406, 6201.56589038772, 6217.347178546188, 6240.906386404116, 4926.285688575208, 4972.7513624318635, 4974.5274198951765, 5013.147764879979, 5048.949510504848, 3855.556610989716, 4534.274516077412, 4547.272436410225, 4549.112922979987, 4554.225781617133, 5720.742566584514, 5746.2529871116485, 5746.569007324384, 5750.626209296466, 5754.539636829044, 6190.3190649028575, 6256.0, 6310.116583678288, 6314.684265786692, 6327.145835312266, 5688.601797874192, 5756.0, 5759.164921086419, 5765.106408506688, 5782.0, 5351.759170837322, 5445.782168713286, 5457.481539253765, 5472.699001554953, 5474.042042642602, 6298.11826344303, 6356.459700925473, 6366.299707032223, 6399.392057754506, 6476.060971159061, 5161.406438259648, 5992.8801423972145, 6300.621962682254, 6304.211973503312, 6329.145565348619, 6253.78111766086, 6417.96791016042, 6442.194725659293, 6456.289808121009, 6479.319671434431, 5337.692265395003, 5884.08796636522, 6240.594361366497, 6294.6852657366035, 6299.275583307795, 6314.842128946813, 6319.58922670025, 6322.2729386119345, 6323.462505687068, 6332.050192471166, 5968.31364393083, 6004.849878753066, 6123.326434092335, 6173.197484327009, 6176.413240742122, 6117.3270940197435, 6155.938440615537, 6156.138140660221, 6164.291106522628, 6172.537059720449, 5922.289325280994, 6022.277326720821, 6023.849403764941, 6037.969810151097, 6101.969490152521, 5963.91054134197, 6105.217149441772, 6138.388398386059, 6161.229846269216, 6184.226971628546, 6123.5876691309595, 6126.94520042658, 6130.306415576139, 6131.590761709946, 6141.854248382413], "value": 5740.861463536341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.56113400774336, 514.8095204774233, 517.629894625343, 527.4725274725274, 530.6497711510403, 32188.67811321889, 45115.77442112789, 46418.0, 46570.534294657045, 49364.753176234066, 46457.30314045292, 47020.0, 47186.0, 47210.29184562234, 47407.76296118523, 8643.913560864405, 9660.27547933906, 9881.0, 9914.355566888129, 9916.157126644244, 11726.648200553997, 12006.339651319175, 12011.879881201163, 12279.877201227962, 12282.754344913097, 11858.584949526768, 11969.7606047879, 11973.281603103842, 11980.640580782547, 11996.940015299911, 8672.826543469095, 11625.53497860093, 11855.703607409798, 11887.108466864976, 12009.519619215222, 11946.701332466671, 12080.97311728504, 12090.576829810914, 12124.030077593772, 12134.696632584257, 43167.78416107899, 43585.56414435847, 43608.3458748119, 43624.78187609073, 43631.34552981708, 2650.642163307948, 3200.9839950800097, 3249.992502324287, 3250.6261779895376, 3438.9312213755834, 30658.54012189819, 30698.846505767553, 30851.382972340652, 31020.22449438782, 31392.0, 2678.665166854143, 2753.2979090332037, 2764.350377661248, 2783.053761721025, 2865.7134286571386, 512200.9999999963, 512546.0, 513029.0, 514329.0, 515224.0, 35530.644693552735, 36485.90542283736, 65374.673126634065, 65729.0, 65825.67087164533, 36482.08794780152, 37916.43125353122, 38122.23755524874, 38286.0, 38385.616143838764, 14804.259787010606, 15044.398224070914, 15047.849521504864, 15048.774268385983, 15056.94601377893, 11973.281603103842, 12300.876991229974, 12932.224066556037, 13151.60545183646, 13218.074734768474, 8110.513369197867, 8162.755117346489, 8174.591270436455, 8201.22088401601, 8214.1375155609, 3275.459549174407, 3300.5709257796334, 3302.5211344354934, 3302.983485082559, 3343.481760327153, 987.2694206287296, 1005.4972513743129, 1020.5917632946881, 1053.952572134256, 1075.2741899218154, 30251.394972100436, 30362.089137325915, 30730.924417645274, 30814.67166082255, 30848.84575577108, 1076.5586109695143, 1084.2356138922041, 1097.0948967102172, 1103.8178700514486, 1118.0943435817014, 13635.863641363658, 15793.605159871095, 16173.514794556182, 16491.83508164927, 16508.917455412648, 702389.0, 705953.0, 708732.0, 709436.0, 709552.0, 66871.66564167148, 67219.6556068876, 67237.32762672409, 67251.66374168098, 67482.66258668773, 1286.6654669785923, 1394.3516264936852, 1431.0269017068285, 1448.2469116059801, 1448.7826825976188, 24909.0, 31210.531842022843, 31484.68515314909, 31677.524837127923, 31837.522437163, 56128.43871561396, 56354.0, 56995.0, 57338.0, 57387.71306143443, 588853.0, 591503.0, 592723.0, 599076.0, 599436.0, 23933.401664958514, 26714.46571068529, 27302.726972730812, 27768.166954991382, 28585.42829143447, 47903.5209647899, 48411.03177936351, 48797.0, 49309.75345123395, 50262.7486862578, 38235.61764382321, 38695.80652096721, 38887.6111238884, 39352.60647393604, 40368.0, 31812.04563863088, 38695.613043870326, 38896.41655375115, 38901.02747431337, 39001.829945101694, 38369.0, 38807.0, 39171.80414097911, 39389.80305098456, 39460.802695986335, 33611.3277734439, 34051.31897361989, 34382.0, 34576.654233457346, 34756.9572912813, 24881.377965551004, 24963.251102466955, 25357.228386684674, 25495.872520637276, 25607.97568097255], "value": 19557.77049443918, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.9164183879547, 383.77740910272047, 387.16758968218335, 395.28453499166494, 402.80463974972133, 20093.39719808406, 21497.355079347606, 22191.44521386966, 22640.66039009414, 22676.0, 21079.60932230912, 21215.469613259676, 21423.250186243484, 21732.0, 22158.556828863413, 5798.188253644493, 5949.613275137124, 5975.402459754033, 6129.264488261392, 6156.5998210116195, 4192.937105943398, 4423.690341676084, 6019.819405417845, 6386.393292637192, 6736.124303840494, 5761.855953601152, 6638.6348750818925, 6712.194536655608, 6739.561928474658, 6743.7524058049175, 6535.052417399451, 6616.139901812758, 6714.865702685967, 6720.865582688343, 6740.056392105133, 3754.624537546251, 5381.784728610851, 5496.148097044965, 5550.639208451437, 5860.941390586082, 26132.216033518835, 26284.342891427677, 26745.73254267452, 26998.73001269982, 27281.0, 2030.8172264496118, 2132.5948069866677, 2199.1533259694975, 2216.6785816056745, 2302.435903203726, 14868.851311486855, 15077.321520531603, 15149.318280677397, 15169.544913652608, 15602.921985390114, 1571.6542360680633, 1621.4000819696787, 1689.493152054391, 1745.4938067960277, 1784.4735802938164, 113801.43099284533, 117295.99999999914, 118326.0, 118576.0, 119336.20995684985, 10718.89281107179, 10962.890371096346, 11267.042301404472, 11273.379964102052, 11390.829137562943, 14273.072250303698, 14321.427142914166, 14358.784618230735, 14365.497207597684, 14367.568972930829, 8633.870491942625, 8850.645974160961, 8862.158094980967, 8926.509042002623, 8948.73153805387, 8841.55792210387, 8868.206514947771, 8878.467291962503, 8970.82058358829, 8986.820263594691, 5510.1459273812625, 6823.215330236987, 6857.6740280963695, 6863.6568171591225, 6869.965650171717, 1599.6240883392393, 1667.7915260592426, 1801.3605170164665, 1846.2891786662233, 1866.4400679796006, 885.3713863157102, 945.8912225094065, 957.0, 972.9270304727032, 1001.6193846338344, 20715.689264661043, 21843.563128737336, 21986.0, 22035.228766993077, 22051.33845984623, 976.8339382305026, 981.9950900245453, 998.2463240253562, 1009.3489699144011, 1025.108155904353, 11417.0, 11668.649940501798, 11720.355380454157, 11722.58970936013, 11786.528538858349, 255717.0, 256162.15756763663, 590802.0, 607820.9608951927, 626228.0, 94548.0, 118261.40869295599, 118519.0, 119454.0, 121748.0, 1027.465717826726, 1047.9685609431729, 1050.6375300521308, 1071.614218881202, 1074.822654262038, 19052.523686907385, 19124.0, 19230.0, 19418.417447476597, 19438.8056119437, 47275.290870637655, 48042.5195748052, 49076.50923490719, 49092.75453622709, 49247.50752492573, 71323.64338178276, 71986.0, 72011.91982120377, 72830.27169728446, 73091.63454182695, 3970.642642162219, 7864.129592698509, 8103.638643664695, 8317.500949943023, 8344.0, 8630.266427353745, 10844.0, 12831.165974211646, 13040.54358097462, 13429.194248345131, 8622.82754344922, 10798.0, 10839.566417343218, 11044.392558409365, 11247.38139402308, 9981.950090249502, 10381.792364152523, 10918.454077296263, 11036.944815275872, 11178.496967636313, 11106.888931110585, 11325.207235493437, 11348.773024539629, 11629.825552616549, 11667.883321166679, 6942.930570694431, 8083.8383232333845, 8183.058948221031, 8251.885995390545, 8439.071702112886, 9108.817823643623, 9296.581653825458, 11307.491162897853, 11316.490757916079, 11524.539018439169], "value": 9670.274860376723, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.5392997275887, 1563.1558958162595, 1592.8168260650025, 1840.825121613447, 1977.2189984955942, 7845.843083138334, 8673.522956237412, 8920.464772113677, 8921.01868794432, 9057.184853363196, 7275.417966562675, 8684.913150868506, 8728.956355218232, 8894.0, 8905.866412003808, 2095.4132842803965, 2315.6294992801154, 2357.5874222011166, 2398.20859116492, 2400.603900356439, 2399.0883464283547, 2420.3586049696846, 2421.1889017179265, 2426.284246147382, 2428.0, 2356.74075851657, 2360.9291721248305, 2389.199618127929, 2399.4601214726636, 2404.879756012201, 2019.5254115282896, 2041.8468614853946, 2071.720317757106, 2292.266474728088, 2334.8365614407, 2378.8691621960784, 2379.785819276256, 2404.4349577849193, 2404.675368825221, 2405.3625789165885, 8738.60676269563, 8815.823683526294, 8843.292536597057, 8919.018907920123, 8919.375643704943, 5256.237845512379, 5407.621056630574, 5419.132938729824, 5425.104857698475, 5455.427180146107, 3357.5635167428322, 3411.198368383428, 3576.266865292635, 3578.159132603836, 3583.122135076897, 1040.5369610523321, 1045.090771029202, 1047.4291511126455, 1048.26621365044, 1064.4305296666246, 11781.999999999915, 11816.763664726743, 11818.409079545989, 11822.113341499336, 11855.229410088317, 4599.816007359668, 4660.97669511657, 4761.142994260997, 4767.4517430495935, 4787.640926930494, 9946.850797238047, 10001.34991225568, 10022.949885250673, 10038.698839034842, 10060.094591486655, 5354.116570765858, 5361.839144825662, 5385.7307134643115, 5387.622866399314, 5388.084025715638, 4320.546342634041, 4478.395416618715, 4778.04439112179, 4919.114559379346, 4923.630727695366, 3902.9852238417648, 3967.603239676067, 3989.3218152914073, 3996.760194388346, 4035.1526179502634, 1287.1183239480986, 1291.7416516696699, 1299.3368488334947, 1308.0908768405866, 1308.3196737696344, 890.0476490155601, 900.0054939291988, 905.0, 916.6241840845222, 950.4500049451175, 5889.352171261158, 5894.911576326359, 5948.345681974979, 6027.0, 6096.268447786294, 724.3625609463638, 887.9866801997975, 892.5358813416987, 896.3994123936978, 920.1442658327819, 3773.509443772347, 3833.2333533293454, 3879.2435475082652, 3948.309045916953, 3983.083890705154, 10628.89371106279, 10658.73353166177, 10748.355098694103, 10922.781544369227, 11068.944655276671, 9221.53892305382, 10693.9465302673, 10928.01647851697, 11010.944945275221, 11027.283226590245, 7943.563104029218, 8187.79530511742, 8317.792055198548, 8337.207965243293, 8433.915660843313, 5220.216967454912, 5659.462351076641, 5881.882362352643, 5907.2911250650195, 5988.5508586855285, 9738.75653108678, 9825.852612210965, 9839.655612053544, 9851.556679949275, 10079.143272821599, 9865.753356166153, 10176.287659863738, 10244.897551024693, 10394.376337419779, 10437.32157409766, 6822.385985261152, 6958.617276049866, 7163.3552980232025, 7552.66013029404, 7727.961360193163, 8294.87557686623, 8388.286995605442, 8412.537310447984, 8470.57647117654, 8481.321494280319, 7318.048653674989, 7325.194228634739, 7440.218777028334, 7534.0582427196605, 7589.962050189713, 7235.059442272682, 7277.38142257914, 7331.193568707545, 7348.632568371667, 7374.741884034033, 6972.0, 7003.894941575779, 7039.577625342496, 7040.3311685391855, 7057.082579264663, 4524.117797029548, 4584.908301834012, 4704.882377940579, 4887.36464259644, 4906.141425250638, 4077.6737860970456, 4585.793639286173, 4721.952780472288, 4748.762561871962, 4980.651354405301], "value": 4565.101198526015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.7366714787378, 314.92284390324374, 318.43001028159597, 321.33644025088194, 324.58939441606356, 4408.977955110228, 4925.754071913414, 5008.068666097114, 5351.625386222966, 5403.432639572848, 4590.19671557477, 5125.8462246132585, 5353.357597088345, 5378.273933019042, 5418.130744893016, 3345.8159801211045, 3866.845326186949, 3927.666148377377, 4085.0391811930144, 4089.9386509202386, 4066.5323487798987, 4102.04652650093, 4116.814743336542, 4128.60778226068, 4130.566290539495, 4094.5905409458965, 4105.692073094515, 4105.7331273467125, 4110.815013324393, 4118.19695159444, 3523.612402635708, 3919.902002449933, 4096.201240758053, 4106.7125301228925, 4120.958790412087, 3281.885134020322, 3299.340131973591, 3387.0516255448515, 3576.928461430783, 3597.9820100899587, 4733.384659994179, 4733.952660473386, 5195.402528709209, 5200.737679109561, 5218.712970786605, 3878.4182372644045, 4691.835785747482, 4763.237881938873, 4763.809447622091, 4802.951970480285, 4812.350332705075, 4850.5877000455, 5002.97439024999, 5665.8668266346895, 5675.404082571353, 3403.6936675699308, 3846.4614953906475, 3873.786941718204, 3992.5807790181902, 3998.10042740384, 5286.524212820865, 5368.033753924292, 5669.461401166924, 5670.461306175948, 5992.704012792947, 3434.6050204226835, 3582.6417358263966, 4024.2353952749468, 4067.918641627151, 4072.0, 3623.9094022649647, 4049.088954985105, 4118.485189351331, 4135.028268356934, 4161.210679407824, 4110.23960567296, 4459.531749166355, 4793.832215872428, 4825.372701548847, 4832.299316599074, 5642.037930086737, 5646.830595082154, 5728.51307638847, 5734.225879506297, 5794.130880367894, 1223.3088305107585, 1395.8902672375486, 1420.8650178233052, 1421.630376102221, 1431.9475185738474, 867.6659486097898, 874.7813046738315, 876.1151237250295, 928.7306681062552, 944.9086305317369, 5238.869028274324, 5292.603054770908, 5301.814436494704, 5304.761285742151, 5309.212663351694, 5433.646812957145, 5661.924234395452, 5689.345725241567, 5794.82615521535, 5805.0, 4935.950640493621, 5262.736863156827, 5264.736763161826, 5377.5160235578205, 5387.24578559002, 2631.7236690147643, 2838.9858050710027, 3040.2855328997666, 3057.3579548295106, 3099.302656902179, 4994.700317980933, 5941.58408911372, 6113.266408030976, 6123.203983482208, 6133.221365803956, 5335.718533102192, 5985.815177460283, 5992.250968628921, 6002.759889604455, 6014.774533422574, 5326.840194794162, 6123.081228014074, 6123.663198524124, 6135.785247516315, 6139.723712432862, 5119.974400127976, 5286.91618218272, 5858.326292476419, 5897.365463853165, 5899.764009439746, 4777.307290442797, 5704.486596206195, 5762.567807414377, 5792.9710351447975, 5796.739146738491, 5682.130524085311, 6112.908306375319, 6114.296855861633, 6118.357572455006, 6121.969390153199, 3148.9055328340187, 3353.9161520961197, 3504.159001839591, 3579.6599323064265, 3904.4143378492317, 4973.438679080205, 5352.919706204488, 5542.61201715876, 5802.680852552981, 5821.893840170354, 4846.321514987834, 5439.646422982493, 5681.119426488901, 5708.372079071381, 5708.885822283448, 4621.953780462152, 4996.275540046746, 5203.282916637576, 5438.211459338453, 5657.010023245998, 4606.0, 5015.97172579613, 5198.0, 5252.579793616425, 5476.12382018891, 4762.476127625889, 5200.855811721415, 5262.736863156751, 5265.341832270966, 5653.943460565341, 5074.07814420012, 5516.724163791875, 5554.777808887599, 5564.582656300713, 5581.358143813513], "value": 4114.776559619854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.8281939647198, 635.8823617630738, 726.201178703426, 733.6955163607104, 739.8853177757446, 14921.328540215689, 14935.626609334771, 15219.391224351026, 15442.0, 15495.302711377986, 14019.719605607881, 14095.78856317156, 14166.78749818751, 14171.149731016147, 14172.50396236132, 718.5078221418319, 741.3031750154864, 889.4574309671095, 894.1773568317147, 912.4935660708304, 897.8787863638423, 900.3967341880956, 902.2917010147036, 902.4404868981208, 904.3895370624834, 938.9295802814783, 948.7580666929923, 950.1116456113522, 952.014664821906, 952.8475443928972, 748.7267147491181, 918.3709159225884, 936.0171819589438, 943.4905151218403, 949.3449519831295, 700.7197121151529, 702.596007295807, 702.6592102830124, 703.4231929817552, 706.0574133531742, 14715.852841471555, 14725.631859203499, 14728.116313021254, 14732.410703571844, 14744.820414366824, 12124.636260912186, 12196.14626976112, 12199.512019519208, 12456.252624842447, 12463.0, 10819.296745711503, 10910.963458471433, 10998.955099265557, 11346.319220846775, 11546.769064618744, 3971.146203566247, 3978.9403158952637, 3978.980105099456, 4063.878083657495, 4163.250614889319, 82625.0, 83258.58370708168, 83273.75089373664, 84081.57959210165, 84086.0, 9411.341206115643, 9414.529273536296, 9508.049195080575, 9662.067586482592, 9790.608375665037, 20538.0, 21465.67801482979, 21535.784642153692, 21922.780772192073, 21933.890330548566, 7074.0, 7271.527350722186, 7431.29402706742, 7568.394528437711, 7741.148473667892, 3682.1879522882455, 3691.3724666806706, 3855.1291428458103, 3941.881743547641, 3954.5649978502342, 3264.1186879542392, 3337.6495467975997, 3358.378699940519, 3358.462645976632, 3361.865525379006, 560.4675558219708, 593.6853467662146, 605.3613437823051, 608.2427377914437, 618.4125081172814, 465.57399979019493, 499.7251511668568, 538.7064050092708, 577.4023885278716, 577.5610535992676, 8100.554469504116, 8101.270885620323, 8176.509409435453, 8189.508629482131, 8270.917290827014, 465.8206590462696, 515.6622412319937, 592.0408937521244, 597.5876645114856, 615.2340336281359, 3820.7707537547835, 3917.9020524487114, 3968.7023473239046, 3994.9201015979515, 4070.841152035152, 88640.0, 91863.0, 92775.0, 93358.0, 93536.0, 74254.62872685603, 77388.0, 77702.83445748319, 81492.59253703694, 83282.33435331381, 55500.0, 66098.34754131186, 66341.0, 67623.66188169028, 68292.31707682958, 8137.79655508617, 8169.754907352789, 8171.591420428836, 8255.834883302421, 8308.83382332362, 42324.57675423206, 42731.786341068095, 42870.78564607157, 42888.0, 43284.13431731285, 55090.0, 57398.85202296014, 59832.0, 60436.697816510634, 60493.0, 10645.627403040857, 10943.616973405893, 11091.057260132979, 11196.104311654884, 11601.419929003347, 11365.829512557153, 12067.396630168632, 12131.09016823724, 12184.75630487403, 12324.927172701895, 9655.710328690151, 11128.554857805597, 11370.260933039657, 11812.76374472523, 12108.031357491203, 11426.828597570877, 11459.942700286445, 11699.0, 11764.35296058725, 11855.110866685205, 11190.272632279202, 11196.720081998015, 11459.942700286445, 11470.391752166233, 11600.30398176112, 9358.672446464341, 9358.812823743623, 9522.619095236112, 9708.75728106803, 10021.799564008825, 7684.193159718149, 7816.6091695416135, 8609.870851937101, 8930.464172149692, 9554.283428742732], "value": 6768.7485390995, "unit": "score"}], "name": "dromaeo_css", "value": 7189.205578116092, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 112.0, 113.0, 160.0, 89.0, 165.0, 165.0, 167.0, 97.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 116.0, 165.0, 117.0, 118.0, 159.0, 141.0, 180.0, 111.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 182.0, 184.0, 183.0, 183.0, 180.0, 184.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 61.0, 108.0, 82.0, 80.0, 60.0, 54.0, 95.0, 56.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 98.0, 71.0, 77.0, 69.0, 93.0, 86.0, 100.0, 136.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 135.0, 72.0, 134.0, 130.0, 102.0, 86.0, 88.0, 85.0], "value": 101.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 93.0, 94.0, 93.0, 95.0, 96.0, 94.0, 93.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 86.0, 85.0, 84.0, 84.0, 85.0, 91.0, 131.0], "value": 90.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 56.0, 55.0, 54.0, 56.0, 54.0, 76.0, 56.0, 54.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 81.0, 78.0, 71.0, 71.0, 69.0, 70.0, 69.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 84.0, 82.0, 84.0, 88.0, 107.0, 108.0, 81.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 149.0, 142.0, 138.0, 135.0, 138.0, 136.0, 134.0, 184.0, 125.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 166.0, 161.0, 160.0, 161.0, 162.0, 161.0, 162.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 65.0, 65.0, 64.0, 66.0, 67.0, 65.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1486.1999999999998, "alertThreshold": 2.0}]} 21:02:39 INFO - Return code: 0 21:02:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:02:39 ERROR - # TBPL SUCCESS # 21:02:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:02:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:02:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:02:39 INFO - 2016-05-24 21:02:39.473 screenresolution[1689:10059] starting screenresolution argv=screenresolution get 21:02:39 INFO - 2016-05-24 21:02:39.475 screenresolution[1689:10059] Display 0: 1600x1200x32@60 21:02:39 INFO - 2016-05-24 21:02:39.481 screenresolution[1690:10062] starting screenresolution argv=screenresolution list 21:02:39 INFO - Available Modes on Display 0 21:02:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:02:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:02:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:02:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:02:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:02:39 INFO - Intel Iris: 21:02:39 INFO - Chipset Model: Intel Iris 21:02:39 INFO - Type: GPU 21:02:39 INFO - Bus: Built-In 21:02:39 INFO - VRAM (Dynamic, Max): 1536 MB 21:02:39 INFO - Vendor: Intel (0x8086) 21:02:39 INFO - Device ID: 0x0a2e 21:02:39 INFO - Revision ID: 0x0009 21:02:39 INFO - Displays: 21:02:39 INFO - Display: 21:02:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:02:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:02:39 INFO - Display Serial Number: 1600x1200 60 21:02:39 INFO - Main Display: Yes 21:02:39 INFO - Mirror: Off 21:02:39 INFO - Online: Yes 21:02:39 INFO - Rotation: Supported 21:02:39 INFO - Return code: 0 21:02:39 INFO - Running post-action listener: _resource_record_post_action 21:02:39 INFO - ##### 21:02:39 INFO - ##### Finished run-tests step (success) 21:02:39 INFO - ##### 21:02:39 INFO - Running post-run listener: _resource_record_post_run 21:02:39 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36742656; Write bytes: 695520768; Read time: 238; Write time: 1926 21:02:39 INFO - TinderboxPrint: CPU usage
54.0% 21:02:39 INFO - TinderboxPrint: I/O read bytes / time
36,742,656 / 238 21:02:39 INFO - TinderboxPrint: I/O write bytes / time
695,520,768 / 1,926 21:02:39 INFO - TinderboxPrint: CPU idle
2,228.1 (46.0%) 21:02:39 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 21:02:39 INFO - TinderboxPrint: CPU user
1,643.2 (33.9%) 21:02:39 INFO - TinderboxPrint: Swap in / out
432,779,264 / 0 21:02:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382153216; Write bytes: 376215040; Read time: 28366; Write time: 784 21:02:39 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35771904; Write bytes: 296417280; Read time: 225; Write time: 1081 21:02:40 INFO - Running post-run listener: _upload_blobber_files 21:02:40 INFO - Blob upload gear active. 21:02:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:02:40 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 21:02:40 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'] 21:02:40 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 21:02:40 INFO - (blobuploader) - INFO - Open directory for files ... 21:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:02:41 INFO - (blobuploader) - INFO - Done attempting. 21:02:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:02:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:02:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:02:42 INFO - (blobuploader) - INFO - Done attempting. 21:02:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:02:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:02:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:02:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:02:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:02:45 INFO - (blobuploader) - INFO - Done attempting. 21:02:45 INFO - (blobuploader) - INFO - Iteration through files over. 21:02:45 INFO - Return code: 0 21:02:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:02:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:02:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84a6f6e26bc117369cb5528739d37a2a95b844283ca96d08bdf79a1fcf35a6371ee9b07fd98d92bdb05dd7512117be777e5565ba49900d4bb819722d47e41ba7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124fedd53051b34ed2197de386ce59d21490a713af2fd42291017d31926a1dff302fc5948a5934ee6a0b9ce70f72e40a7a903d9d7ccf59a29fc2f7eb59b58834", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1288420650d9723c65ca206d2418ce1a811a80785da6a4dabb798c578abfe132841baf4e96c5c45b69402fbdc0c62a9f3b516922c0c586703edc72622452a08"} 21:02:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:02:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:02:45 INFO - Contents: 21:02:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84a6f6e26bc117369cb5528739d37a2a95b844283ca96d08bdf79a1fcf35a6371ee9b07fd98d92bdb05dd7512117be777e5565ba49900d4bb819722d47e41ba7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124fedd53051b34ed2197de386ce59d21490a713af2fd42291017d31926a1dff302fc5948a5934ee6a0b9ce70f72e40a7a903d9d7ccf59a29fc2f7eb59b58834", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1288420650d9723c65ca206d2418ce1a811a80785da6a4dabb798c578abfe132841baf4e96c5c45b69402fbdc0c62a9f3b516922c0c586703edc72622452a08"} 21:02:45 INFO - Running post-run listener: copy_logs_to_upload_dir 21:02:45 INFO - Copying logs to upload dir... 21:02:45 INFO - mkdir: /builds/slave/test/build/upload/logs 21:02:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.164161 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-24 21:02:45.230328) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 21:02:45.234152) ========= 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.noPow6p16F/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.qN2h9X77au/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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/84a6f6e26bc117369cb5528739d37a2a95b844283ca96d08bdf79a1fcf35a6371ee9b07fd98d92bdb05dd7512117be777e5565ba49900d4bb819722d47e41ba7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124fedd53051b34ed2197de386ce59d21490a713af2fd42291017d31926a1dff302fc5948a5934ee6a0b9ce70f72e40a7a903d9d7ccf59a29fc2f7eb59b58834", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1288420650d9723c65ca206d2418ce1a811a80785da6a4dabb798c578abfe132841baf4e96c5c45b69402fbdc0c62a9f3b516922c0c586703edc72622452a08"} build_url:https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010882 build_url: 'https://queue.taskcluster.net/v1/task/GpOgHQipQTO5pjXGXU8ENg/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/84a6f6e26bc117369cb5528739d37a2a95b844283ca96d08bdf79a1fcf35a6371ee9b07fd98d92bdb05dd7512117be777e5565ba49900d4bb819722d47e41ba7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/124fedd53051b34ed2197de386ce59d21490a713af2fd42291017d31926a1dff302fc5948a5934ee6a0b9ce70f72e40a7a903d9d7ccf59a29fc2f7eb59b58834", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1288420650d9723c65ca206d2418ce1a811a80785da6a4dabb798c578abfe132841baf4e96c5c45b69402fbdc0c62a9f3b516922c0c586703edc72622452a08"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 21:02:45.265335) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:02:45.265662) ========= 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.noPow6p16F/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.qN2h9X77au/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.004974 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 21:02:45.317649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 21:02:45.318366) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 21:02:45.319157) ========= ========= Total master_lag: 0.20 =========