builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0385 starttime: 1464222172.66 results: success (0) buildid: 20160525153953 builduid: 4a947a504c3b426ba6f100c9d80b30a5 revision: 9ef45b3ae61d40b772319a314205ddacfe00cff9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.660297) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.660781) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.661074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.682212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.682586) ========= 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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-25 17:22:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-05-25 17:22:52 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113997 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.816454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.816800) ========= 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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.019872 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:52.866042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 17:22:52.866393) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9ef45b3ae61d40b772319a314205ddacfe00cff9 --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 9ef45b3ae61d40b772319a314205ddacfe00cff9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TpZ7NdcAyF/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.I40ifJHPbc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-25 17:22:52,942 truncating revision to first 12 chars 2016-05-25 17:22:52,942 Setting DEBUG logging. 2016-05-25 17:22:52,942 attempt 1/10 2016-05-25 17:22:52,942 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9ef45b3ae61d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 17:22:54,016 unpacking tar archive at: fx-team-9ef45b3ae61d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.334064 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 17:22:54.219931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:54.220250) ========= script_repo_revision: 9ef45b3ae61d40b772319a314205ddacfe00cff9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:54.220640) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:54.220933) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 17:22:54.236697) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-25 17:22:54.237005) ========= /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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:22:54 INFO - MultiFileLogger online at 20160525 17:22:54 in /builds/slave/test 17:22:54 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 17:22:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:22:54 INFO - {'append_to_log': False, 17:22:54 INFO - 'base_work_dir': '/builds/slave/test', 17:22:54 INFO - 'blob_upload_branch': 'Fx-Team', 17:22:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:22:54 INFO - 'branch': 'Fx-Team', 17:22:54 INFO - 'buildbot_json_path': 'buildprops.json', 17:22:54 INFO - 'config_files': ('talos/mac_config.py',), 17:22:54 INFO - 'default_actions': ('clobber', 17:22:54 INFO - 'read-buildbot-config', 17:22:54 INFO - 'download-and-extract', 17:22:54 INFO - 'populate-webroot', 17:22:54 INFO - 'create-virtualenv', 17:22:54 INFO - 'install', 17:22:54 INFO - 'run-tests'), 17:22:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:22:54 INFO - 'download_minidump_stackwalk': True, 17:22:54 INFO - 'download_symbols': 'ondemand', 17:22:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:22:54 INFO - 'tooltool.py': '/tools/tooltool.py', 17:22:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:22:54 INFO - '/tools/misc-python/virtualenv.py')}, 17:22:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:22:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:22:54 INFO - 'installer_path': 'installer.exe', 17:22:54 INFO - 'log_level': 'info', 17:22:54 INFO - 'log_name': 'talos', 17:22:54 INFO - 'log_to_console': True, 17:22:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:22:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:22:54 INFO - 'opt_config_files': (), 17:22:54 INFO - 'pip_index': False, 17:22:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:22:54 INFO - 'cmd': ('bash', 17:22:54 INFO - '-c', 17:22:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:22:54 INFO - 'enabled': True, 17:22:54 INFO - 'halt_on_failure': False, 17:22:54 INFO - 'name': 'check_screen_resolution'},), 17:22:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:22:54 INFO - 'cmd': ('bash', 17:22:54 INFO - '-c', 17:22:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:22:54 INFO - 'enabled': True, 17:22:54 INFO - 'halt_on_failure': False, 17:22:54 INFO - 'name': 'check_screen_resolution'},), 17:22:54 INFO - 'run_cmd_checks_enabled': True, 17:22:54 INFO - 'sps_profile': False, 17:22:54 INFO - 'sps_profile_interval': 0, 17:22:54 INFO - 'suite': 'dromaeojs', 17:22:54 INFO - 'system_bits': '32', 17:22:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:22:54 INFO - 'title': 't-yosemite-r7-0385', 17:22:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:22:54 INFO - 'use_talos_json': True, 17:22:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:22:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:22:54 INFO - 'work_dir': 'build'} 17:22:54 INFO - ##### 17:22:54 INFO - ##### Running clobber step. 17:22:54 INFO - ##### 17:22:54 INFO - Running pre-action listener: _resource_record_pre_action 17:22:54 INFO - Running main action method: clobber 17:22:54 INFO - rmtree: /builds/slave/test/build 17:22:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:22:55 INFO - Running post-action listener: _resource_record_post_action 17:22:55 INFO - ##### 17:22:55 INFO - ##### Finished clobber step (success) 17:22:55 INFO - ##### 17:22:55 INFO - ##### 17:22:55 INFO - ##### Running read-buildbot-config step. 17:22:55 INFO - ##### 17:22:55 INFO - Running pre-action listener: _resource_record_pre_action 17:22:55 INFO - Running main action method: read_buildbot_config 17:22:55 INFO - Using buildbot properties: 17:22:55 INFO - { 17:22:55 INFO - "project": "", 17:22:55 INFO - "product": "firefox", 17:22:55 INFO - "script_repo_revision": "production", 17:22:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:22:55 INFO - "repository": "", 17:22:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:22:55 INFO - "buildid": "20160525153953", 17:22:55 INFO - "pgo_build": "False", 17:22:55 INFO - "basedir": "/builds/slave/test", 17:22:55 INFO - "buildnumber": 22, 17:22:55 INFO - "slavename": "t-yosemite-r7-0385", 17:22:55 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:22:55 INFO - "platform": "yosemite_r7", 17:22:55 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:22:55 INFO - "branch": "fx-team", 17:22:55 INFO - "repo_path": "integration/fx-team", 17:22:55 INFO - "revision": "9ef45b3ae61d40b772319a314205ddacfe00cff9", 17:22:55 INFO - "stage_platform": "macosx64", 17:22:55 INFO - "builduid": "4a947a504c3b426ba6f100c9d80b30a5", 17:22:55 INFO - "slavebuilddir": "test" 17:22:55 INFO - } 17:22:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:22:55 INFO - Running post-action listener: _resource_record_post_action 17:22:55 INFO - ##### 17:22:55 INFO - ##### Finished read-buildbot-config step (success) 17:22:55 INFO - ##### 17:22:55 INFO - ##### 17:22:55 INFO - ##### Running download-and-extract step. 17:22:55 INFO - ##### 17:22:55 INFO - Running pre-action listener: _resource_record_pre_action 17:22:55 INFO - Running main action method: download_and_extract 17:22:55 INFO - mkdir: /builds/slave/test/build/tests 17:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:55 INFO - https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:22:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:22:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:22:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:22:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:22:57 INFO - Downloaded 1199 bytes. 17:22:57 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:22:57 INFO - Using the following test package requirements: 17:22:57 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:22:57 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:22:57 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:22:57 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'jsshell-mac.zip'], 17:22:57 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:22:57 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:22:57 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:22:57 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:22:57 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:22:57 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:22:57 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:22:57 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:57 INFO - https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:22:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:22:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:22:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:22:58 INFO - Downloaded 18622358 bytes. 17:22:58 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'] 17:22:58 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 17:23:01 INFO - Return code: 0 17:23:01 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 17:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:01 INFO - https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:23:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:23:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:23:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:23:02 INFO - Downloaded 18622358 bytes. 17:23:02 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'] 17:23:02 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 17:23:06 INFO - Return code: 0 17:23: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')]} 17:23:06 INFO - https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:23:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:23:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:23:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:23:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:23:08 INFO - Downloaded 10834034 bytes. 17:23:08 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'] 17:23:08 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 17:23:08 INFO - Return code: 0 17:23:08 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')]} 17:23:08 INFO - https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:23:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:23:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:23:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpY0XcafTIynQLopphfbuA/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 17:23:12 INFO - Downloaded 110500743 bytes. 17:23:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:23:12 INFO - mkdir: /builds/slave/test/properties 17:23:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:23:12 INFO - Writing to file /builds/slave/test/properties/build_url 17:23:12 INFO - Contents: 17:23:12 INFO - build_url:https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:23:13 INFO - Running post-action listener: _resource_record_post_action 17:23:13 INFO - Running post-action listener: set_extra_try_arguments 17:23:13 INFO - ##### 17:23:13 INFO - ##### Finished download-and-extract step (success) 17:23:13 INFO - ##### 17:23:13 INFO - ##### 17:23:13 INFO - ##### Running populate-webroot step. 17:23:13 INFO - ##### 17:23:13 INFO - Running pre-action listener: _resource_record_pre_action 17:23:13 INFO - Running main action method: populate_webroot 17:23:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:23:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:23:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:23:13 INFO - '--tppagecycles', 17:23:13 INFO - '7'], 17:23:13 INFO - 'tests': ['tsvgm']}}, 17:23:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:23:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:23:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:23:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:23:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['damp', 'tps']}, 17:23:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'tests': ['damp', 'tps']}, 17:23:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['dromaeo_dom']}, 17:23:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:23:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['a11yr', 17:23:13 INFO - 'ts_paint', 17:23:13 INFO - 'tpaint', 17:23:13 INFO - 'sessionrestore', 17:23:13 INFO - 'sessionrestore_no_auto_restore', 17:23:13 INFO - 'tabpaint']}, 17:23:13 INFO - 'other-e10s': {'tests': ['a11yr', 17:23:13 INFO - 'ts_paint', 17:23:13 INFO - 'tpaint', 17:23:13 INFO - 'sessionrestore', 17:23:13 INFO - 'sessionrestore_no_auto_restore', 17:23:13 INFO - 'tabpaint']}, 17:23:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:23:13 INFO - 'ts_paint', 17:23:13 INFO - 'tpaint', 17:23:13 INFO - 'sessionrestore', 17:23:13 INFO - 'sessionrestore_no_auto_restore', 17:23:13 INFO - 'tabpaint']}, 17:23:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:23:13 INFO - 'ts_paint', 17:23:13 INFO - 'tpaint', 17:23:13 INFO - 'sessionrestore', 17:23:13 INFO - 'sessionrestore_no_auto_restore', 17:23:13 INFO - 'tabpaint']}, 17:23:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['a11yr', 17:23:13 INFO - 'ts_paint', 17:23:13 INFO - 'tpaint', 17:23:13 INFO - 'sessionrestore', 17:23:13 INFO - 'sessionrestore_no_auto_restore', 17:23:13 INFO - 'tabpaint']}, 17:23:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['a11yr', 17:23:13 INFO - 'ts_paint', 17:23:13 INFO - 'tpaint', 17:23:13 INFO - 'sessionrestore', 17:23:13 INFO - 'sessionrestore_no_auto_restore', 17:23:13 INFO - 'tabpaint']}, 17:23:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['tsvgx', 17:23:13 INFO - 'tsvgr_opacity', 17:23:13 INFO - 'tart', 17:23:13 INFO - 'tscrollx', 17:23:13 INFO - 'cart']}, 17:23:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:23:13 INFO - 'tsvgr_opacity', 17:23:13 INFO - 'tart', 17:23:13 INFO - 'tscrollx', 17:23:13 INFO - 'cart']}, 17:23:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'talos_options': ['--disable-e10s'], 17:23:13 INFO - 'tests': ['tp5o']}, 17:23:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'tests': ['tp5o']}, 17:23:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'talos_options': ['--disable-e10s', 17:23:13 INFO - '--xperf_path', 17:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:13 INFO - 'tests': ['tp5n']}, 17:23:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:13 INFO - 'talos_options': ['--xperf_path', 17:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:13 INFO - 'tests': ['tp5n']}}, 17:23:13 INFO - 'talos.zip': {'path': '', 17:23:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:23:13 INFO - Running post-action listener: _resource_record_post_action 17:23:13 INFO - ##### 17:23:13 INFO - ##### Finished populate-webroot step (success) 17:23:13 INFO - ##### 17:23:13 INFO - ##### 17:23:13 INFO - ##### Running create-virtualenv step. 17:23:13 INFO - ##### 17:23:13 INFO - Running pre-action listener: _resource_record_pre_action 17:23:13 INFO - Running main action method: create_virtualenv 17:23:13 INFO - Creating virtualenv /builds/slave/test/build/venv 17:23:13 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 17:23:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:23:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:23:13 INFO - Using real prefix '/tools/python27' 17:23:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:23:14 INFO - Installing distribute.............................................................................................................................................................................................done. 17:23:17 INFO - Installing pip.................done. 17:23:17 INFO - Return code: 0 17:23:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:17 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:17 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 17:23:17 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 17:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:17 INFO - 'HOME': '/Users/cltbld', 17:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:17 INFO - 'LOGNAME': 'cltbld', 17:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:17 INFO - 'MOZ_NO_REMOTE': '1', 17:23:17 INFO - 'NO_EM_RESTART': '1', 17:23:17 INFO - 'PAGER': '/bin/cat', 17:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:17 INFO - 'PWD': '/builds/slave/test', 17:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:17 INFO - 'SHELL': '/bin/bash', 17:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:17 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:17 INFO - 'USER': 'cltbld', 17:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:17 INFO - 'XPC_FLAGS': '0x0', 17:23:17 INFO - 'XPC_SERVICE_NAME': '0', 17:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:17 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 17:23:17 INFO - Cleaning up... 17:23:17 INFO - Return code: 0 17:23:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:17 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:17 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 17:23:17 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 17:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:17 INFO - 'HOME': '/Users/cltbld', 17:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:17 INFO - 'LOGNAME': 'cltbld', 17:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:17 INFO - 'MOZ_NO_REMOTE': '1', 17:23:17 INFO - 'NO_EM_RESTART': '1', 17:23:17 INFO - 'PAGER': '/bin/cat', 17:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:17 INFO - 'PWD': '/builds/slave/test', 17:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:17 INFO - 'SHELL': '/bin/bash', 17:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:17 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:17 INFO - 'USER': 'cltbld', 17:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:17 INFO - 'XPC_FLAGS': '0x0', 17:23:17 INFO - 'XPC_SERVICE_NAME': '0', 17:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:17 INFO - Downloading/unpacking psutil>=3.1.1 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:23:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:20 INFO - Installing collected packages: psutil 17:23:20 INFO - Running setup.py install for psutil 17:23:20 INFO - building 'psutil._psutil_osx' extension 17:23:20 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 17:23:21 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 17:23:21 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 17:23:21 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 17:23:21 INFO - building 'psutil._psutil_posix' extension 17:23:21 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 17:23:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:23:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:23:21 INFO - ^ 17:23:21 INFO - 1 warning generated. 17:23:21 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 17:23:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:21 INFO - Successfully installed psutil 17:23:21 INFO - Cleaning up... 17:23:21 INFO - Return code: 0 17:23:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:23:21 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')]} 17:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:21 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')]} 17:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:21 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:21 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 17:23:21 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 17:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:21 INFO - 'HOME': '/Users/cltbld', 17:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:21 INFO - 'LOGNAME': 'cltbld', 17:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:21 INFO - 'MOZ_NO_REMOTE': '1', 17:23:21 INFO - 'NO_EM_RESTART': '1', 17:23:21 INFO - 'PAGER': '/bin/cat', 17:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:21 INFO - 'PWD': '/builds/slave/test', 17:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:21 INFO - 'SHELL': '/bin/bash', 17:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:21 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:21 INFO - 'USER': 'cltbld', 17:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:21 INFO - 'XPC_FLAGS': '0x0', 17:23:21 INFO - 'XPC_SERVICE_NAME': '0', 17:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:23:24 INFO - Installing collected packages: mozsystemmonitor 17:23:24 INFO - Running setup.py install for mozsystemmonitor 17:23:24 INFO - Successfully installed mozsystemmonitor 17:23:24 INFO - Cleaning up... 17:23:24 INFO - Return code: 0 17:23:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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')]} 17:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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', '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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:23: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 blobuploader==1.2.4 17:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:24 INFO - 'HOME': '/Users/cltbld', 17:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:24 INFO - 'LOGNAME': 'cltbld', 17:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:24 INFO - 'MOZ_NO_REMOTE': '1', 17:23:24 INFO - 'NO_EM_RESTART': '1', 17:23:24 INFO - 'PAGER': '/bin/cat', 17:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:24 INFO - 'PWD': '/builds/slave/test', 17:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:24 INFO - 'SHELL': '/bin/bash', 17:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:24 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:24 INFO - 'USER': 'cltbld', 17:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:24 INFO - 'XPC_FLAGS': '0x0', 17:23:24 INFO - 'XPC_SERVICE_NAME': '0', 17:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:24 INFO - Downloading/unpacking blobuploader==1.2.4 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:27 INFO - Downloading blobuploader-1.2.4.tar.gz 17:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:23:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:23:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:28 INFO - Downloading docopt-0.6.1.tar.gz 17:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:23:28 INFO - Installing collected packages: blobuploader, requests, docopt 17:23:28 INFO - Running setup.py install for blobuploader 17:23:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:23:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:23:28 INFO - Running setup.py install for requests 17:23:29 INFO - Running setup.py install for docopt 17:23:29 INFO - Successfully installed blobuploader requests docopt 17:23:29 INFO - Cleaning up... 17:23:29 INFO - Return code: 0 17:23:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:29 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:29 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 17:23:29 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 17:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:29 INFO - 'HOME': '/Users/cltbld', 17:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:29 INFO - 'LOGNAME': 'cltbld', 17:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:29 INFO - 'MOZ_NO_REMOTE': '1', 17:23:29 INFO - 'NO_EM_RESTART': '1', 17:23:29 INFO - 'PAGER': '/bin/cat', 17:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:29 INFO - 'PWD': '/builds/slave/test', 17:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:29 INFO - 'SHELL': '/bin/bash', 17:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:29 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:29 INFO - 'USER': 'cltbld', 17:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:29 INFO - 'XPC_FLAGS': '0x0', 17:23:29 INFO - 'XPC_SERVICE_NAME': '0', 17:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:23:29 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-SEXQiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:23:29 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-oy0XRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:23:29 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-1wcdrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-craGpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-89geWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-i4pAdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-MqXv78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-J9Chwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-XTOwVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-f0KMjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-3a2aiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-NjS9zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:23:30 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-RYngU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:23:31 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-BPEpid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:31 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-4TZxZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:23:31 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-AbKKH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:23:31 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-WOahTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:23:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:23:31 INFO - Running setup.py install for manifestparser 17:23:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:23:31 INFO - Running setup.py install for mozcrash 17:23:31 INFO - Running setup.py install for mozdebug 17:23:31 INFO - Running setup.py install for mozdevice 17:23:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Running setup.py install for mozfile 17:23:32 INFO - Running setup.py install for mozhttpd 17:23:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Running setup.py install for mozinfo 17:23:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Running setup.py install for mozInstall 17:23:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:23:32 INFO - Running setup.py install for mozleak 17:23:32 INFO - Running setup.py install for mozlog 17:23:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Running setup.py install for moznetwork 17:23:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Running setup.py install for mozprocess 17:23:33 INFO - Running setup.py install for mozprofile 17:23:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Running setup.py install for mozrunner 17:23:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Running setup.py install for mozscreenshot 17:23:33 INFO - Running setup.py install for moztest 17:23:33 INFO - Running setup.py install for mozversion 17:23:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:23:34 INFO - Cleaning up... 17:23:34 INFO - Return code: 0 17:23:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:34 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:34 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 17:23:34 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 17:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:34 INFO - 'HOME': '/Users/cltbld', 17:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:34 INFO - 'LOGNAME': 'cltbld', 17:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:34 INFO - 'MOZ_NO_REMOTE': '1', 17:23:34 INFO - 'NO_EM_RESTART': '1', 17:23:34 INFO - 'PAGER': '/bin/cat', 17:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:34 INFO - 'PWD': '/builds/slave/test', 17:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:34 INFO - 'SHELL': '/bin/bash', 17:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:34 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:34 INFO - 'USER': 'cltbld', 17:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:34 INFO - 'XPC_FLAGS': '0x0', 17:23:34 INFO - 'XPC_SERVICE_NAME': '0', 17:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:23:34 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-gYTeeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:23:34 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)) 17:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:23:34 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-u_eMpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:23:34 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)) 17:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:23:34 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-VqsgvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:23:34 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)) 17:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:23:34 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Cp7ex2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:23:34 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)) 17:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:23:34 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-6oyXsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:23:34 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)) 17:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:23:34 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2_ubUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-KCcvAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-UwyMma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vh4EtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-kSgxIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ALAVs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vu3JhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-WbHxdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rnhTh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vQYrvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:35 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)) 17:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:23:35 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-wkMCcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:23:36 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)) 17:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:23:36 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ERR0Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:23:36 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)) 17:23:36 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)) 17:23:36 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)) 17:23:36 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)) 17:23:36 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)) 17:23:36 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)) 17:23:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:23:36 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 17:23:36 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 17:23:36 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 17:23:36 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 17:23:36 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 17:23:36 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 17:23:38 INFO - Downloading blessings-1.6.tar.gz 17:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:23:38 INFO - Installing collected packages: blessings 17:23:38 INFO - Running setup.py install for blessings 17:23:38 INFO - Successfully installed blessings 17:23:38 INFO - Cleaning up... 17:23:38 INFO - Return code: 0 17:23:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:23:39 INFO - Reading from file tmpfile_stdout 17:23:39 INFO - Current package versions: 17:23:39 INFO - blessings == 1.6 17:23:39 INFO - blobuploader == 1.2.4 17:23:39 INFO - docopt == 0.6.1 17:23:39 INFO - manifestparser == 1.1 17:23:39 INFO - mozInstall == 1.12 17:23:39 INFO - mozcrash == 0.17 17:23:39 INFO - mozdebug == 0.1 17:23:39 INFO - mozdevice == 0.48 17:23:39 INFO - mozfile == 1.2 17:23:39 INFO - mozhttpd == 0.7 17:23:39 INFO - mozinfo == 0.9 17:23:39 INFO - mozleak == 0.1 17:23:39 INFO - mozlog == 3.2 17:23:39 INFO - moznetwork == 0.27 17:23:39 INFO - mozprocess == 0.22 17:23:39 INFO - mozprofile == 0.28 17:23:39 INFO - mozrunner == 6.11 17:23:39 INFO - mozscreenshot == 0.1 17:23:39 INFO - mozsystemmonitor == 0.3 17:23:39 INFO - moztest == 0.7 17:23:39 INFO - mozversion == 1.4 17:23:39 INFO - psutil == 3.1.1 17:23:39 INFO - requests == 1.2.3 17:23:39 INFO - wsgiref == 0.1.2 17:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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')]} 17:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:39 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:39 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 17:23:39 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 17:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:39 INFO - 'HOME': '/Users/cltbld', 17:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:39 INFO - 'LOGNAME': 'cltbld', 17:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:39 INFO - 'MOZ_NO_REMOTE': '1', 17:23:39 INFO - 'NO_EM_RESTART': '1', 17:23:39 INFO - 'PAGER': '/bin/cat', 17:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:39 INFO - 'PWD': '/builds/slave/test', 17:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:39 INFO - 'SHELL': '/bin/bash', 17:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:39 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:39 INFO - 'USER': 'cltbld', 17:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:39 INFO - 'XPC_FLAGS': '0x0', 17:23:39 INFO - 'XPC_SERVICE_NAME': '0', 17:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 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)) 17:23:39 INFO - Cleaning up... 17:23:39 INFO - Return code: 0 17:23:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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')]} 17:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:39 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 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:39 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 17:23:39 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 17:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:39 INFO - 'HOME': '/Users/cltbld', 17:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:39 INFO - 'LOGNAME': 'cltbld', 17:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:39 INFO - 'MOZ_NO_REMOTE': '1', 17:23:39 INFO - 'NO_EM_RESTART': '1', 17:23:39 INFO - 'PAGER': '/bin/cat', 17:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:39 INFO - 'PWD': '/builds/slave/test', 17:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:39 INFO - 'SHELL': '/bin/bash', 17:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:39 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:39 INFO - 'USER': 'cltbld', 17:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:39 INFO - 'XPC_FLAGS': '0x0', 17:23:39 INFO - 'XPC_SERVICE_NAME': '0', 17:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:39 INFO - Downloading/unpacking jsonschema 17:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:23:42 INFO - Downloading/unpacking functools32 (from jsonschema) 17:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:42 INFO - Downloading functools32-3.2.3-2.tar.gz 17:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:23:42 INFO - warning: no files found matching '*.txt' 17:23:42 INFO - no previously-included directories found matching 'build' 17:23:42 INFO - no previously-included directories found matching 'dist' 17:23:42 INFO - no previously-included directories found matching '.git*' 17:23:42 INFO - Installing collected packages: jsonschema, functools32 17:23:42 INFO - Running setup.py install for functools32 17:23:42 INFO - warning: no files found matching '*.txt' 17:23:42 INFO - no previously-included directories found matching 'build' 17:23:42 INFO - no previously-included directories found matching 'dist' 17:23:42 INFO - no previously-included directories found matching '.git*' 17:23:42 INFO - Successfully installed jsonschema functools32 17:23:42 INFO - Cleaning up... 17:23:42 INFO - Return code: 0 17:23:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106838a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10683e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e2563660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, '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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 17:23:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:23:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:42 INFO - 'HOME': '/Users/cltbld', 17:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:42 INFO - 'LOGNAME': 'cltbld', 17:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:42 INFO - 'MOZ_NO_REMOTE': '1', 17:23:42 INFO - 'NO_EM_RESTART': '1', 17:23:42 INFO - 'PAGER': '/bin/cat', 17:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:42 INFO - 'PWD': '/builds/slave/test', 17:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:42 INFO - 'SHELL': '/bin/bash', 17:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:23:42 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:42 INFO - 'USER': 'cltbld', 17:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:42 INFO - 'XPC_FLAGS': '0x0', 17:23:42 INFO - 'XPC_SERVICE_NAME': '0', 17:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:43 INFO - Downloading/unpacking flake8 17:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:23:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:23:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:23:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:23:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:23:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:23:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:23:46 INFO - no previously-included directories found matching 'docs/_build' 17:23:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:23:47 INFO - Running setup.py install for pep8 17:23:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:23:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:23:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:23:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:23:47 INFO - no previously-included directories found matching 'docs/_build' 17:23:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:23:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:23:47 INFO - Cleaning up... 17:23:47 INFO - Return code: 0 17:23:47 INFO - Running post-action listener: _resource_record_post_action 17:23:47 INFO - Running post-action listener: _start_resource_monitoring 17:23:47 INFO - Starting resource monitoring. 17:23:47 INFO - ##### 17:23:47 INFO - ##### Finished create-virtualenv step (success) 17:23:47 INFO - ##### 17:23:47 INFO - ##### 17:23:47 INFO - ##### Running install step. 17:23:47 INFO - ##### 17:23:47 INFO - Running pre-action listener: _resource_record_pre_action 17:23:47 INFO - Running main action method: install 17:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:23:47 INFO - Reading from file tmpfile_stdout 17:23:47 INFO - Detecting whether we're running mozinstall >=1.0... 17:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:23:47 INFO - Reading from file tmpfile_stdout 17:23:47 INFO - Output received: 17:23:47 INFO - Usage: mozinstall [options] installer 17:23:47 INFO - Options: 17:23:47 INFO - -h, --help show this help message and exit 17:23:47 INFO - -d DEST, --destination=DEST 17:23:47 INFO - Directory to install application into. [default: 17:23:47 INFO - "/builds/slave/test"] 17:23:47 INFO - --app=APP Application being installed. [default: firefox] 17:23:47 INFO - mkdir: /builds/slave/test/build/application 17:23:47 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'] 17:23:47 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 17:24:21 INFO - Reading from file tmpfile_stdout 17:24:21 INFO - Output received: 17:24:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:24:21 INFO - Running post-action listener: _resource_record_post_action 17:24:21 INFO - ##### 17:24:21 INFO - ##### Finished install step (success) 17:24:21 INFO - ##### 17:24:21 INFO - ##### 17:24:21 INFO - ##### Running run-tests step. 17:24:21 INFO - ##### 17:24:21 INFO - Running pre-action listener: _resource_record_pre_action 17:24:21 INFO - Running main action method: run_tests 17:24:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:24:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:24:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:24:21 INFO - 2016-05-25 17:24:21.521 screenresolution[1917:11991] starting screenresolution argv=screenresolution get 17:24:21 INFO - 2016-05-25 17:24:21.523 screenresolution[1917:11991] Display 0: 1600x1200x32@60 17:24:21 INFO - 2016-05-25 17:24:21.529 screenresolution[1918:11993] starting screenresolution argv=screenresolution list 17:24:21 INFO - Available Modes on Display 0 17:24:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:24:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:24:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:24:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:24:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:24:21 INFO - Intel Iris: 17:24:21 INFO - Chipset Model: Intel Iris 17:24:21 INFO - Type: GPU 17:24:21 INFO - Bus: Built-In 17:24:21 INFO - VRAM (Dynamic, Max): 1536 MB 17:24:21 INFO - Vendor: Intel (0x8086) 17:24:21 INFO - Device ID: 0x0a2e 17:24:21 INFO - Revision ID: 0x0009 17:24:21 INFO - Displays: 17:24:21 INFO - Display: 17:24:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:24:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:24:21 INFO - Display Serial Number: 1600x1200 60 17:24:21 INFO - Main Display: Yes 17:24:21 INFO - Mirror: Off 17:24:21 INFO - Online: Yes 17:24:21 INFO - Rotation: Supported 17:24:21 INFO - Return code: 0 17:24:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:24:21 INFO - Python 2.7.3 17:24:21 INFO - Return code: 0 17:24:21 INFO - grabbing minidump binary from tooltool 17:24:21 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')]} 17:24:21 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 0x1066dcd30>, '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 0x106500db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066d3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:24:21 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 17:24:21 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 17:24:21 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 17:24:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:24:21 INFO - Return code: 0 17:24:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:24:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:24:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:24:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:24:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:24:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:24:23 INFO - Return code: 0 17:24:23 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/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 17:24:23 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/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --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 17:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TpZ7NdcAyF/Render', 17:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:23 INFO - 'HOME': '/Users/cltbld', 17:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:23 INFO - 'LOGNAME': 'cltbld', 17:24:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:24:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:23 INFO - 'MOZ_NO_REMOTE': '1', 17:24:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:24:23 INFO - 'NO_EM_RESTART': '1', 17:24:23 INFO - 'PAGER': '/bin/cat', 17:24:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:23 INFO - 'PWD': '/builds/slave/test', 17:24:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:23 INFO - 'SHELL': '/bin/bash', 17:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I40ifJHPbc/Listeners', 17:24:23 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 17:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:23 INFO - 'USER': 'cltbld', 17:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:23 INFO - 'XPC_FLAGS': '0x0', 17:24:23 INFO - 'XPC_SERVICE_NAME': '0', 17:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:23 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/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 17:24:23 INFO - mozversion application_buildid: 20160525153953 17:24:23 INFO - mozversion application_changeset: 9ef45b3ae61d40b772319a314205ddacfe00cff9 17:24:23 INFO - mozversion application_display_name: Nightly 17:24:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:24:23 INFO - mozversion application_name: Firefox 17:24:23 INFO - mozversion application_remotingname: firefox 17:24:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:24:23 INFO - mozversion application_vendor: Mozilla 17:24:23 INFO - mozversion application_version: 49.0a1 17:24:23 INFO - mozversion platform_buildid: 20160525153953 17:24:23 INFO - mozversion platform_changeset: 9ef45b3ae61d40b772319a314205ddacfe00cff9 17:24:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:24:23 INFO - mozversion platform_version: 49.0a1 17:24:23 INFO - using testdate: 1464222263 17:24:23 INFO - actual date: 1464222263 17:24:23 INFO - starting webserver on 'localhost:50804' 17:24:23 INFO - SUITE-START | Running 2 tests 17:24:23 INFO - TEST-START | dromaeo_css 17:24:23 INFO - operating with platform_type : mac_ 17:24:23 INFO - Initialising browser for dromaeo_css test... 17:24:23 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpfjFBuG/profile http://localhost:50804/getInfo.html) 17:24:25 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 17:24:25 INFO - PROCESS | 1936 | colorDepth:24 17:24:25 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 17:24:25 INFO - PROCESS | 1936 | __metrics 17:24:25 INFO - PROCESS | 1936 | 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" 17:24:25 ERROR - PROCESS | 1936 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:24:26 INFO - TEST-INFO | 1936: exit 0 17:24:26 INFO - Browser initialized. 17:24:26 INFO - Running cycle 1/1 for dromaeo_css test... 17:24:26 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpfjFBuG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:38 INFO - PROCESS | 1938 | RSS: Main: 241373184 17:24:38 INFO - PROCESS | 1938 | 17:25:29 INFO - PROCESS | 1938 | 1464222329613 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] 17:27:40 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50804/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50804/tests/dromaeo/cssquery-ext.html) 17:27:42 INFO - PROCESS | 1938 | RSS: Main: 333918208 17:27:42 INFO - PROCESS | 1938 | 17:30:45 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50804/tests/dromaeo/cssquery-ext.html (next: http://localhost:50804/tests/dromaeo/cssquery-jquery.html) 17:30:45 INFO - PROCESS | 1938 | RSS: Main: 378703872 17:30:45 INFO - PROCESS | 1938 | 17:33:48 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50804/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50804/tests/dromaeo/cssquery-mootools.html) 17:33:48 INFO - PROCESS | 1938 | RSS: Main: 369655808 17:33:48 INFO - PROCESS | 1938 | 17:36:51 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50804/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50804/tests/dromaeo/cssquery-prototype.html) 17:36:51 INFO - PROCESS | 1938 | RSS: Main: 371539968 17:36:51 INFO - PROCESS | 1938 | 17:39:55 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50804/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50804/tests/dromaeo/cssquery-yui.html) 17:39:55 INFO - PROCESS | 1938 | RSS: Main: 338173952 17:39:55 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50804/tests/dromaeo/cssquery-yui.html (next: http://localhost:50804/tests/dromaeo/cssquery-dojo.html) 17:42:58 INFO - PROCESS | 1938 | RSS: Main: 380272640 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | __start_tp_report 17:42:58 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 17:42:58 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 17:42:58 INFO - PROCESS | 1938 | |i|pagename|runs| 17:42:58 INFO - PROCESS | 1938 | |0;dojo.html;3394.81328526931;3404.9785064480648;3410.7953522788634;3414.2147306119577;3469.670381313776;5486.61593688442;5511.311086114236;5532.5573954083675;5560.972195139028;5590.077637189869;5548.140038294071;5590.217369568253;5642.520385767215;5664.461876121762;5701.77192912284;5018.04657115147;5056.469070747556;5107.693538387709;5112.054269960051;5211.296474975868;5163.63854530183;5184.922226166611;5199.012187684328;5219.164933610623;5238.109521381374;5026.1204289249235;5088.949110508885;5092.490750924915;5093.236014597803;5159.71621560814;5129.974005198937;5139.229115632647;5147.819826306099;5151.793928242866;5162.509561591681;5055.545000949895;5078.847634570969;5080.13637681595;5089.8727531812;5096.388433388017;5439.184122381635;5459.317585301837;5497.780088796443;5507.449255074461;5518.255035570187;6090.056041313604;6116.96941515294;6164.660943648098;6169.5064394848305;6174.938250617482;5601.943980560183;6097.4036925702085;6217.285012223607;6224.813255602339;6237.407446292595;5645.633033852787;5659.886802263932;5687.687177205211;5759.971200143972;5774.39368866263;6281.152044473937;6290.968545157337;6294.8111556653375;6306.810795676137;6316.778912738032;5871.735771890275;5939.13882487037;6035.547333949971;6070.9870567032185;6086.756759456233;6114.449440885012;6173.876522469615;6179.073139029092;6188.81433556994;6189.319174890758;6098.664573448503;6103.755849766048;6118.405621347521;6128.044828652003;6136.917362303726;5806.767729290866;5985.16207730918;6037.245344331958;6112.777056696867;6134.840557113091;4089.488813898263;4794.2808578713475;4836.3712717347225;4841.419029716457;4862.027594481047;4315.546867578922;4318.784060796947;4350.543192964693;4353.6081752641785;4359.716618419792;5502.0921547944945;5598.524125449382;5617.494425501724;5622.128570071645;5635.802746903921;5224.190250511177;6114.755409783559;6190.102435146879;6205.100260462208;6208.068789681584;5614.270144881221;5623.718814059281;5625.943740562624;5650.773969041193;5653.349864765604;5199.480051994846;5249.396319423239;5251.711155886463;5288.286081379041;5294.867628309323;6201.286852012076;6217.595856269328;6258.186435763321;6271.843203919939;6278.591891527036;6094.592115505723;6194.5044396449075;6196;6197.194364732556;6209.130721698875;5627.7163789891965;6145.0782382642965;6212.223472065992;6264.310925798068;6274.686265686789;5951.315598706205;5954.970225148846;6067.120267561268;6092.964196086587;6103.633781972918;6081.148639190428;6118.541109416722;6118.724657390339;6121.418465245794;6131.570790044654;4993.251012348177;5812.21535092774;5822;5874.206982057539;5880.588358814842;5948.553858460719;5969.791057312973;5970.402959703995;5973.342932277359;6003.87992240144;5763.682997435181;5771.624844385102;5787;5805.8548536286935;5858.179854820243;5691.715414229355;5864.208331875142;5952.851178720567;5956.374580668968;5972.193753843176;5739.110437882135;5829.941700583109;5848.093545500455;5853.882922341614;5855.882882342415 17:42:58 INFO - PROCESS | 1938 | |1;ext.html;512.1012622846903;514.1003244322436;517.4695936664918;529.4837533404929;533.2135100726427;35022.64977350226;41956;42145.15709685804;43754;43771.3434298485;42956;43663;43677.56322436775;43706.562934370646;43890.78054609722;8619.267362274215;8810.38327317088;9539.284553658468;9757.707268781913;11815.409229538489;9303.581338839735;11560.075193984461;11873.346965916871;11899.940500297529;12231.938840305786;11849.407529623528;11930;11943.223690460092;11954.103442241823;11966.222195557304;8614.483131012159;11548.769024619503;11747.647570572897;11754.706132346675;12025.338606376647;11724.824127638092;11804.822927656092;11816;11825.290482570987;11846.229995050295;40005.59994400048;40400.39399409011;40409.797951010354;40427.989300267436;40480.797596012126;3190.0429871038828;3337.732981361485;3374.2745309758275;3474.2356681530023;3599.748017638766;24379.878100609385;28474.288142796595;28561.428771424664;30119.69880301191;30210;2235.5193633368904;2287.4167087392775;2758.3793646429394;2772.625695531098;2796.734310240524;429776;515139;515902;517137.8286217165;517586;60591.39408605946;60622.69688651617;61132.69433652803;61151;61182;64397.35602644007;64408;64412;64555.03167452492;64558;14695.412183512753;16149.273282702306;16318.020918744676;16343.673126537402;16355.42756003558;11653.941730291293;12711.872881271069;12869.485220591072;12997.805032924514;13002.739945201232;16165.030098194145;16360.263788129563;16399.42602008924;16411;16518.42185523501;6172.382761723792;7393.260673932671;7422.925770742331;7482.139553951257;7609;882.1751662195867;990.9009099090177;1031.7111208861531;1043.9373637581618;1066.1204506282347;25845.74154258471;28248.71751282461;28323.008694695585;29009;29243.976460823767;1072.4316112460408;1078.3907092492716;1098.368438148068;1110.7334239782556;1134.8014097533064;13796.793048104286;14686.339114739862;14763.40946362155;14772.852271477363;15064.774028389584;610440;611979;615869;617129;622525;57498.425015750145;57514.71242643844;57679.13481297784;57847;57879.42120578825;1288.9419976101099;1405.6134562995053;1438.3527412664134;1445.8409574946747;1447.6887469194173;29773.255668608457;30490.542641859945;30619.387612248076;30760.846195768878;31225.68774312228;59149;63116.68441657762;63737.36262637314;63753.681231593546;63798;641524.7923760351;650044;651358;651556;652659;36787.44818827666;46268.07463850637;47331.29003064888;47555.76222118867;47681.523184769096;47987;48428.273575895684;48598.27102593393;48753.75623121862;48774.512254876994;38062;39346.52531221338;39656.60343396529;39666;40611.78164655065;35196.82401588078;38798.6120138795;39034.6096539031;39871.40192897167;39912.60087399089;38150.09249537456;38840.6115938837;39510.80244598874;39511.81464556068;39521.80239098786;31417.685823141477;32523;34169.241383447305;34254.65745342515;34299;24651.50696986086;24976;25181.87409062943;25496;25654.61518077193 17:42:58 INFO - PROCESS | 1938 | |2;jquery.html;386.36057325126916;386.4377330983419;390.0560643243349;393.10176247274984;414.9958500414995;13152.473901043946;20181.19275228989;20507.282245121423;20950.790492095075;20969.895150524226;15410.537683869474;16687.33250669975;19490.512737181576;20306.796932030677;20479;5636.18275350074;5790;5841.831633673261;5852.356240813507;5852.356240813507;5555.777768889239;5655.69918918649;5759.107338362561;5796.82609521715;5813.30240371156;5701.828945131632;5723.399043100456;5731.283589551306;5731.684757338345;5740.741666625012;5391.703456309881;5677.744501497443;5679.886402271973;5722.313322401317;5725.227094342273;4968.2299243616935;4984.750762461862;4988.900221995576;4990.65065445419;4991.001799640087;23758.40603984897;23796.92913818882;25144;25260;25264.11575594864;2072.212559227484;2077.2833372486466;2319.211468100837;2396.664466974625;2405.867677277749;14770.926145369313;14843.480478183319;14902.850971490254;14992.550223493312;15228.314725837365;1646.3743777364525;1754.0440459949232;1764.2061072517417;1787.892726436418;1854.4992851930037;83811.5809420949;105275;106340;108050;108235.91764082338;9256.768580785534;10323.793524129474;10340.586376544854;10465.738356541147;10770.730731731614;13520.59438216855;13600.86399135996;13601.659958500919;13622.318884055758;13634.318284085755;5999.5500337473995;6192.938070619326;7089.397401220954;7713.344365728976;7807.102183248886;6572.474202063823;7543.321101100927;7618.428617853683;7701.691932322644;7708.49894756839;5331.333583302087;6353.936460635335;6505.466904545968;6507.7071531781185;6569;1609.3803885504165;1874.5969616532511;1878.4270797406648;1894.2233684189691;1923.086533896416;826.9338452923752;860.0238729042582;915.9924083508088;927.3137877970388;951.5813042261383;18697.719534206997;19140.712889306393;19322;19480.415587532396;19670;891.7057371067532;892.2727976698969;901.3420203251532;919.6643225222746;927.596495524449;9332.72001839946;10198.33710808795;10466.162706983423;10482.68551943443;10489.318194317346;570194;575656;586242;586588;589992;79080;111624;117054.8294517044;117170;117846;964.4695417520336;973.6105557776946;999.2954966748425;1022.4274406332478;1023.5291765787821;17095.401660941807;17241.39655112065;17430.651386972444;17559.824401755817;17594.82405175932;49042.75478622584;50559.74720126376;50636.49363506465;50790;51397;70979;71024.93462598168;71798.64100679463;72290.63854680693;73053.90419143612;7695.076590809139;7799;7819.530828150329;7853.56805375687;8237.79405514867;12183.208091474025;12443.875561244633;12747.426365813375;12786.36068196605;12836.03729720256;9770.36492627977;10198.286119971532;10490.160787137163;10924.672259832218;10932.726681832699;7830.491018083806;8540.74377768668;10623;10754.300970436896;10782.514786834769;10796.622118225825;10893.945530272298;11135.554577816796;11433.1996760226;11556.826647600461;6628.734850605922;7998.240167184108;8032.51804891685;8202.507849529047;8457.238848503664;8943.865842012245;10508.42203678805;10508.842367364343;10717.571297148026;10738.463076846283 17:42:58 INFO - PROCESS | 1938 | |3;mootools.html;1360.1431098408002;1589.9443519476818;1613.4433620400962;1867.3557622620192;2001.859869809113;7757.301842834151;8622.91377086229;8868.778280542982;8872.512011839362;9000.954995225014;7120.857582848353;8817.4709517429;8825.205731484166;8833.558322083902;8837.293016558677;1953.7460130183056;2122.554263604645;2192.1121945612053;2366.2546297916147;2404.0263693204192;2420.685310909588;2453.509298140379;2457.336519139831;2457.373369790701;2457.938551536217;2371.537550177716;2399.892004859777;2409.1929203716777;2420.5037967216676;2421.116292553223;2093.2150443583655;2171.3811563704267;2174.521605246843;2177.2597316912324;2366.6804981327473;2085.384811480617;2347.1784875293683;2426.478307163968;2436.549238390903;2437.427204606916;8736.51949142797;8823.382363234576;8824.602892869836;8830.55847207637;8834.86747698785;5245.580353571743;5400.405955344909;5421.647592906448;5427.592930530226;5432.158015507603;3390.593128824532;3508.4036763272907;3569.6787289144104;3582;3584.462330650423;990.4057565460666;1003.2325271167563;1004.7689031522717;1005.8742657167854;1011.0294117647068;11698.181127321006;11749.823752643717;11750.999999999915;11751.764964700658;11776.528938842435;4699.812007519729;4725.243960966298;4849.175640141114;4855.1018061658;4863.148948933993;9937.354071985297;9937.503124843728;9941.40351578893;9942.751431214278;9980.60077596888;5332.440093790174;5409.323834520685;5424.647397919122;5424.83725488236;5424.891502169935;4189.979050104791;4423.181711383404;4589.908201835945;4775.95224047762;4926.531979461945;3925.175713100281;3997.84008639657;4044.919101617951;4068.2880495913096;4093.85671501496;1099.9890001100046;1296.9805452918213;1299.1807777123665;1308.9738205236033;1311.8360204974379;844.8720957521655;851.2764150472049;889.9110088991179;896.3949334199519;930.9534523273809;5713.571482138773;5721.828345149652;5838.970805145947;5880.8235752927485;5916.763329466859;902.4449963272672;907.2605826251583;907.7458311672742;923.9214666753401;976.9364991275544;3836.8848934532007;3920.3923391874305;3937.9803100984313;3982.323005089084;3987.6211759882763;10339.896601034045;10538.631147909786;10690.839637405445;10704.411257380918;10709.732256693489;10623.575056997634;10645.680629581126;10702;10716.142708583295;10728.356298621951;8062.113167551682;8151.918480815235;8156.306713929383;8287.58562071906;8296.377771667148;5560.4161563035295;5731.627444216113;5754.827355179351;5774.05523183188;5799.246098007232;9628.32601717873;10041.598336066476;10061;10076.345037572535;10094.697159085239;9158.313126515672;9555.140037396668;10180.898191017995;10220.182385409298;10241;6310.747570097328;6467.7736279231885;6670.099536562502;6968.059311992816;7100.076989991268;7770.766876993699;7836.764897053097;7842.901993720998;7850.6074696266105;7866.8819967701675;7215.567065976058;7300.452466065172;7322.597257150908;7446.515976461729;7475.177730449537;7228.891566626399;7238.986541884035;7319.377852882564;7418.591977441292;7453.962730186315;6766.255711871789;6802.557833740791;6882.793516194522;6892.483063770137;6893.482988775761;4040.6363427290507;4655;4847.200211965127;5018.121828679893;5119.92320115206;4130.504339479282;4302.892427689333;4445.266531022345;4578.244589642805;4633.328167415773 17:42:58 INFO - PROCESS | 1938 | |4;prototype.html;267.3183382374944;300.253869135199;301.4167585721629;317.36526946107784;318.4029943855271;4925.793180670732;5074.361332107302;5161.458046905077;5176.223566465027;5238.035736598776;4222.831086756533;4888.80224345036;5012.373453318335;5093.872653183672;5102.953894451638;3109.782315237934;3691.963080369202;3996.4804575405155;4003.1993601279714;4043.2115737431286;3946.3883098119695;4012.779297138656;4020.8540565938715;4021.6380525752675;4043.130726893717;3768.868089616864;3811.4282857571447;3957.6042395760337;3971.6028397160344;4083.7958102094776;3069.539569064647;3831;3970.463987361698;3975.6819454443867;3994.9001274968214;3254.6908043735807;3523.3834079036064;3530.1527633368064;3531.7527773055895;3542.2030043240325;4192.769397683126;4933.7286449245275;5022.296878437021;5025.497450254982;5037.194048952187;4539.251023581105;4581.473130589991;4584.289435137559;4605.792739326705;4607;5021.9748901255625;5147.305113809625;5461.777551244784;5474.7262636868;5498.140966697335;3157.952630710541;3877.379619260933;3891.5524714657895;3909.58949310324;3910.042039700263;5228.8757917048015;5231.947680523184;5353.491418315254;5729.997250481191;5795.710214489259;3246.610406751205;3537.4870643756512;3789.886303410902;3810.561785394715;3949.913773712252;3340.365330587181;3788.507494025814;3998.5005622891417;4008.6993475489453;4018.3524754850373;4179.26862799009;4376.212281789245;4561.977190114028;4616.492185859553;4637.634806248838;5246.291750613695;5480.534154596904;5481.643693159932;5496.477834605706;5515.862103447446;1180.710725872167;1209.1017103147974;1367.3436750359883;1378.4141739760562;1383.633337165652;843.1526316052328;864.5720368417674;869.2958703450225;885.7112900729423;893.1871996483299;4964.751762411865;5056.494350564988;5063.316452278895;5067.7837319043165;5118.822670785666;4621.792019359137;5144.6398752087;5427.294451721251;5531.708633363223;5607.644789188551;4466.263066593976;4727.409073865767;4827.734474603861;5006.374203224597;5009.924851127236;2912.3156058326276;2953.7446585201205;2970.3465237647683;2970.6928951261807;2982.492976194052;5408.675479471244;5491.807786727525;5849.590528662953;5897.2038774765715;5931.881362372728;4759.547842954914;4781.569658730731;5726.850232372179;5856.795012174639;5876.265466816648;5156.767945442464;5188.186682788072;5507.366652834975;5759.65442073477;6053.303870055;5035.370578677665;5323.920141197807;5696.829095127153;5698.2592263005545;5795.623284486495;4503.617192538671;5337.786488540415;5611.6352437091455;5630.95827271956;5708.172315014383;4721.787519561559;5231.633785634969;5483.533899618417;5519.39286678473;5899.823005309848;3266.754993375554;3515.1878497201624;3615.439606860941;3782.905427364338;3864.632859878307;4640.675152739276;5394.595405344537;5701.572382071444;5719.6272894505855;5726.9691455538405;5069.467705890828;5280.841574752763;5316.255724198692;5515.531179849597;5525.4474552544425;5501;5511.53151982086;5513.751881165277;5542.61201715876;5597.944020559742;4358.951292892336;4886.971906658839;5005.674631148963;5382.650127741685;5389.865253368697;4277.593628605277;4516.841910533116;4715.575598196178;5173.92239116406;5561.804212094419;4702.777277907837;5407.215953686772;5444.346678398618;5466.043442397485;5551.528120109674 17:42:58 INFO - PROCESS | 1938 | |5;yui.html;661.199948062844;711.6939715922155;719.7480881691407;721.5598484924195;776.4359118918333;14237;14838.703225935502;14866.108033517969;14873.553793386189;14874.628134296649;11419;13918;13943.442262309496;13965.371558279876;13985.650358741037;653.157426919276;686.1663079358578;880.6301353431542;897.3135551303258;898.6225785170244;850.336737344872;899.7435730816718;900.2932697832204;900.8063266397724;901.1709227510706;938.0994245524305;951.4719330771419;951.8477043673014;952.4666186935339;953.2421724728857;728.6429649471721;899.7390756680555;944.9385789923633;946.1059298962483;946.2524605561598;706.388973537886;706.9081019467487;706.960767671522;708.1926603671818;708.7979925721145;14564.126152430783;14592;14618.707625847423;14625;14661.926690366587;12012.939935300268;12246.81629775554;12265.509379624804;12276.693082672915;12385.504579816796;10593.470326483723;10640.57437702491;10755.946220268848;10968.903109688967;11023.338599684044;3502.842372093262;3996.7002474814212;3999.6600288975474;4039.2931237033695;4140.60664236897;73899.63050184769;75020;76264.61867690682;76274.23725762783;76647;9294;9319.627214911463;9573.760655983657;9608.087231712976;9627.326087173831;16928.661426771643;21415.678764818542;21416.143354265656;21433.67849482259;21439.892800535898;6116;7153.642317884085;7169.856602868018;7300.087489063867;7338.082739657543;3836.731428800013;3939.153082087365;4002.5597184309704;4012.398140278923;4015.77913214776;3361.9831900840827;3462.290230502741;3464.826758662057;3467.531883195736;3468.1849765305133;599.8320470268332;614.5790133758389;616.1497133955113;618.7494064903722;620.7765204526457;465.75780594090884;501.97992080317107;510.32891747352113;518.6758276077452;554.3790954131399;6509.349065093453;7365.852682946311;7561.508501947355;7580.924190758132;7643.617819109022;466.2097744323339;509.16242780625663;514.729766872395;525.4036668381415;528.9576833853282;3307.371599396155;3351.4470112431663;3453.8273086346085;3545.18460754028;3733.570639376452;84794.57602711947;86248;88163;88179.67730484047;88824;74708.62645686737;74850.625746872;75200.87198692025;75649.62175189199;76134;65836.67081664561;68813.65593172002;69699;69817.65091174512;70085;5654.604177707521;6939.340762627744;7027.859442811013;7124.358807707331;7337.3029562191505;42632;43040.35439468473;44055.559444405146;44618.776906115265;45041.32438013367;56148.719256403456;60699.48251293753;61836;62350.68824655848;62494;9486.715398538056;9522.714318570454;9707.174890134134;9768.36505627132;9882;10069.89930100719;10069.993000699871;10191.949040255047;10416.947915260374;10472.947635261775;9272.582733777132;9367.578458969227;9418.199453046567;9637.132658060807;9702.757431063998;9401.435913845191;9513.429194248367;9606.711798646053;9742.171915387271;9873.50632468388;9315.441073535338;9347.579358928728;9349.298802589698;9522.190613797904;9649.372790768577;8038.758837234892;8111.878321825059;8381.960757048344;8449.366297527833;8459.788505287417;7553.395728341829;7743.883841742266;7949.523028618301;8161.428699990944;8328.583570821556 17:42:58 INFO - PROCESS | 1938 | __end_tp_report 17:42:58 INFO - PROCESS | 1938 | __start_cc_report 17:42:58 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1633 17:42:58 INFO - PROCESS | 1938 | __end_cc_report 17:42:58 INFO - PROCESS | 1938 | __startTimestamp1464223378853__endTimestamp 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | ------- Summary: start ------- 17:42:58 INFO - PROCESS | 1938 | Number of tests: 6 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5622.72 Median:5774.39 stddev:625.99 (10.8%) stddev-sans-first:603.02 17:42:58 INFO - PROCESS | 1938 | Values: 3394.8 3405.0 3410.8 3414.2 3469.7 5486.6 5511.3 5532.6 5561.0 5590.1 5548.1 5590.2 5642.5 5664.5 5701.8 5018.0 5056.5 5107.7 5112.1 5211.3 5163.6 5184.9 5199.0 5219.2 5238.1 5026.1 5088.9 5092.5 5093.2 5159.7 5130.0 5139.2 5147.8 5151.8 5162.5 5055.5 5078.8 5080.1 5089.9 5096.4 5439.2 5459.3 5497.8 5507.4 5518.3 6090.1 6117.0 6164.7 6169.5 6174.9 5601.9 6097.4 6217.3 6224.8 6237.4 5645.6 5659.9 5687.7 5760.0 5774.4 6281.2 6291.0 6294.8 6306.8 6316.8 5871.7 5939.1 6035.5 6071.0 6086.8 6114.4 6173.9 6179.1 6188.8 6189.3 6098.7 6103.8 6118.4 6128.0 6136.9 5806.8 5985.2 6037.2 6112.8 6134.8 4089.5 4794.3 4836.4 4841.4 4862.0 4315.5 4318.8 4350.5 4353.6 4359.7 5502.1 5598.5 5617.5 5622.1 5635.8 5224.2 6114.8 6190.1 6205.1 6208.1 5614.3 5623.7 5625.9 5650.8 5653.3 5199.5 5249.4 5251.7 5288.3 5294.9 6201.3 6217.6 6258.2 6271.8 6278.6 6094.6 6194.5 6196.0 6197.2 6209.1 5627.7 6145.1 6212.2 6264.3 6274.7 5951.3 5955.0 6067.1 6093.0 6103.6 6081.1 6118.5 6118.7 6121.4 6131.6 4993.3 5812.2 5822.0 5874.2 5880.6 5948.6 5969.8 5970.4 5973.3 6003.9 5763.7 5771.6 5787.0 5805.9 5858.2 5691.7 5864.2 5952.9 5956.4 5972.2 5739.1 5829.9 5848.1 5853.9 5855.9 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:72897.16 Median:38106.05 stddev:158113.95 (414.9%) stddev-sans-first:158464.10 17:42:58 INFO - PROCESS | 1938 | Values: 512.1 514.1 517.5 529.5 533.2 35022.6 41956.0 42145.2 43754.0 43771.3 42956.0 43663.0 43677.6 43706.6 43890.8 8619.3 8810.4 9539.3 9757.7 11815.4 9303.6 11560.1 11873.3 11899.9 12231.9 11849.4 11930.0 11943.2 11954.1 11966.2 8614.5 11548.8 11747.6 11754.7 12025.3 11724.8 11804.8 11816.0 11825.3 11846.2 40005.6 40400.4 40409.8 40428.0 40480.8 3190.0 3337.7 3374.3 3474.2 3599.7 24379.9 28474.3 28561.4 30119.7 30210.0 2235.5 2287.4 2758.4 2772.6 2796.7 429776.0 515139.0 515902.0 517137.8 517586.0 60591.4 60622.7 61132.7 61151.0 61182.0 64397.4 64408.0 64412.0 64555.0 64558.0 14695.4 16149.3 16318.0 16343.7 16355.4 11653.9 12711.9 12869.5 12997.8 13002.7 16165.0 16360.3 16399.4 16411.0 16518.4 6172.4 7393.3 7422.9 7482.1 7609.0 882.2 990.9 1031.7 1043.9 1066.1 25845.7 28248.7 28323.0 29009.0 29244.0 1072.4 1078.4 1098.4 1110.7 1134.8 13796.8 14686.3 14763.4 14772.9 15064.8 610440.0 611979.0 615869.0 617129.0 622525.0 57498.4 57514.7 57679.1 57847.0 57879.4 1288.9 1405.6 1438.4 1445.8 1447.7 29773.3 30490.5 30619.4 30760.8 31225.7 59149.0 63116.7 63737.4 63753.7 63798.0 641524.8 650044.0 651358.0 651556.0 652659.0 36787.4 46268.1 47331.3 47555.8 47681.5 47987.0 48428.3 48598.3 48753.8 48774.5 38062.0 39346.5 39656.6 39666.0 40611.8 35196.8 38798.6 39034.6 39871.4 39912.6 38150.1 38840.6 39510.8 39511.8 39521.8 31417.7 32523.0 34169.2 34254.7 34299.0 24651.5 24976.0 25181.9 25496.0 25654.6 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:33065.08 Median:388.25 stddev:96451.40 (24842.8%) stddev-sans-first:96690.76 17:42:58 INFO - PROCESS | 1938 | Values: 386.4 386.4 390.1 393.1 415.0 13152.5 20181.2 20507.3 20950.8 20969.9 15410.5 16687.3 19490.5 20306.8 20479.0 5636.2 5790.0 5841.8 5852.4 5852.4 5555.8 5655.7 5759.1 5796.8 5813.3 5701.8 5723.4 5731.3 5731.7 5740.7 5391.7 5677.7 5679.9 5722.3 5725.2 4968.2 4984.8 4988.9 4990.7 4991.0 23758.4 23796.9 25144.0 25260.0 25264.1 2072.2 2077.3 2319.2 2396.7 2405.9 14770.9 14843.5 14902.9 14992.6 15228.3 1646.4 1754.0 1764.2 1787.9 1854.5 83811.6 105275.0 106340.0 108050.0 108235.9 9256.8 10323.8 10340.6 10465.7 10770.7 13520.6 13600.9 13601.7 13622.3 13634.3 5999.6 6192.9 7089.4 7713.3 7807.1 6572.5 7543.3 7618.4 7701.7 7708.5 5331.3 6353.9 6505.5 6507.7 6569.0 1609.4 1874.6 1878.4 1894.2 1923.1 826.9 860.0 916.0 927.3 951.6 18697.7 19140.7 19322.0 19480.4 19670.0 891.7 892.3 901.3 919.7 927.6 9332.7 10198.3 10466.2 10482.7 10489.3 570194.0 575656.0 586242.0 586588.0 589992.0 79080.0 111624.0 117054.8 117170.0 117846.0 964.5 973.6 999.3 1022.4 1023.5 17095.4 17241.4 17430.7 17559.8 17594.8 49042.8 50559.7 50636.5 50790.0 51397.0 70979.0 71024.9 71798.6 72290.6 73053.9 7695.1 7799.0 7819.5 7853.6 8237.8 12183.2 12443.9 12747.4 12786.4 12836.0 9770.4 10198.3 10490.2 10924.7 10932.7 7830.5 8540.7 10623.0 10754.3 10782.5 10796.6 10893.9 11135.6 11433.2 11556.8 6628.7 7998.2 8032.5 8202.5 8457.2 8943.9 10508.4 10508.8 10717.6 10738.5 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5631.02 Median:4852.14 stddev:3177.02 (65.5%) stddev-sans-first:3169.72 17:42:58 INFO - PROCESS | 1938 | Values: 1360.1 1589.9 1613.4 1867.4 2001.9 7757.3 8622.9 8868.8 8872.5 9001.0 7120.9 8817.5 8825.2 8833.6 8837.3 1953.7 2122.6 2192.1 2366.3 2404.0 2420.7 2453.5 2457.3 2457.4 2457.9 2371.5 2399.9 2409.2 2420.5 2421.1 2093.2 2171.4 2174.5 2177.3 2366.7 2085.4 2347.2 2426.5 2436.5 2437.4 8736.5 8823.4 8824.6 8830.6 8834.9 5245.6 5400.4 5421.6 5427.6 5432.2 3390.6 3508.4 3569.7 3582.0 3584.5 990.4 1003.2 1004.8 1005.9 1011.0 11698.2 11749.8 11751.0 11751.8 11776.5 4699.8 4725.2 4849.2 4855.1 4863.1 9937.4 9937.5 9941.4 9942.8 9980.6 5332.4 5409.3 5424.6 5424.8 5424.9 4190.0 4423.2 4589.9 4776.0 4926.5 3925.2 3997.8 4044.9 4068.3 4093.9 1100.0 1297.0 1299.2 1309.0 1311.8 844.9 851.3 889.9 896.4 931.0 5713.6 5721.8 5839.0 5880.8 5916.8 902.4 907.3 907.7 923.9 976.9 3836.9 3920.4 3938.0 3982.3 3987.6 10339.9 10538.6 10690.8 10704.4 10709.7 10623.6 10645.7 10702.0 10716.1 10728.4 8062.1 8151.9 8156.3 8287.6 8296.4 5560.4 5731.6 5754.8 5774.1 5799.2 9628.3 10041.6 10061.0 10076.3 10094.7 9158.3 9555.1 10180.9 10220.2 10241.0 6310.7 6467.8 6670.1 6968.1 7100.1 7770.8 7836.8 7842.9 7850.6 7866.9 7215.6 7300.5 7322.6 7446.5 7475.2 7228.9 7239.0 7319.4 7418.6 7454.0 6766.3 6802.6 6882.8 6892.5 6893.5 4040.6 4655.0 4847.2 5018.1 5119.9 4130.5 4302.9 4445.3 4578.2 4633.3 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4412.24 Median:4985.21 stddev:1343.44 (26.9%) stddev-sans-first:1310.69 17:42:58 INFO - PROCESS | 1938 | Values: 267.3 300.3 301.4 317.4 318.4 4925.8 5074.4 5161.5 5176.2 5238.0 4222.8 4888.8 5012.4 5093.9 5103.0 3109.8 3692.0 3996.5 4003.2 4043.2 3946.4 4012.8 4020.9 4021.6 4043.1 3768.9 3811.4 3957.6 3971.6 4083.8 3069.5 3831.0 3970.5 3975.7 3994.9 3254.7 3523.4 3530.2 3531.8 3542.2 4192.8 4933.7 5022.3 5025.5 5037.2 4539.3 4581.5 4584.3 4605.8 4607.0 5022.0 5147.3 5461.8 5474.7 5498.1 3158.0 3877.4 3891.6 3909.6 3910.0 5228.9 5231.9 5353.5 5730.0 5795.7 3246.6 3537.5 3789.9 3810.6 3949.9 3340.4 3788.5 3998.5 4008.7 4018.4 4179.3 4376.2 4562.0 4616.5 4637.6 5246.3 5480.5 5481.6 5496.5 5515.9 1180.7 1209.1 1367.3 1378.4 1383.6 843.2 864.6 869.3 885.7 893.2 4964.8 5056.5 5063.3 5067.8 5118.8 4621.8 5144.6 5427.3 5531.7 5607.6 4466.3 4727.4 4827.7 5006.4 5009.9 2912.3 2953.7 2970.3 2970.7 2982.5 5408.7 5491.8 5849.6 5897.2 5931.9 4759.5 4781.6 5726.9 5856.8 5876.3 5156.8 5188.2 5507.4 5759.7 6053.3 5035.4 5323.9 5696.8 5698.3 5795.6 4503.6 5337.8 5611.6 5631.0 5708.2 4721.8 5231.6 5483.5 5519.4 5899.8 3266.8 3515.2 3615.4 3782.9 3864.6 4640.7 5394.6 5701.6 5719.6 5727.0 5069.5 5280.8 5316.3 5515.5 5525.4 5501.0 5511.5 5513.8 5542.6 5597.9 4359.0 4887.0 5005.7 5382.7 5389.9 4277.6 4516.8 4715.6 5173.9 5561.8 4702.8 5407.2 5444.3 5466.0 5551.5 17:42:58 INFO - PROCESS | 1938 | 17:42:58 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:17075.68 Median:69951.33 stddev:24262.17 (34.7%) stddev-sans-first:24298.92 17:42:58 INFO - PROCESS | 1938 | Values: 661.2 711.7 719.7 721.6 776.4 14237.0 14838.7 14866.1 14873.6 14874.6 11419.0 13918.0 13943.4 13965.4 13985.7 653.2 686.2 880.6 897.3 898.6 850.3 899.7 900.3 900.8 901.2 938.1 951.5 951.8 952.5 953.2 728.6 899.7 944.9 946.1 946.3 706.4 706.9 707.0 708.2 708.8 14564.1 14592.0 14618.7 14625.0 14661.9 12012.9 12246.8 12265.5 12276.7 12385.5 10593.5 10640.6 10755.9 10968.9 11023.3 3502.8 3996.7 3999.7 4039.3 4140.6 73899.6 75020.0 76264.6 76274.2 76647.0 9294.0 9319.6 9573.8 9608.1 9627.3 16928.7 21415.7 21416.1 21433.7 21439.9 6116.0 7153.6 7169.9 7300.1 7338.1 3836.7 3939.2 4002.6 4012.4 4015.8 3362.0 3462.3 3464.8 3467.5 3468.2 599.8 614.6 616.1 618.7 620.8 465.8 502.0 510.3 518.7 554.4 6509.3 7365.9 7561.5 7580.9 7643.6 466.2 509.2 514.7 525.4 529.0 3307.4 3351.4 3453.8 3545.2 3733.6 84794.6 86248.0 88163.0 88179.7 88824.0 74708.6 74850.6 75200.9 75649.6 76134.0 65836.7 68813.7 69699.0 69817.7 70085.0 5654.6 6939.3 7027.9 7124.4 7337.3 42632.0 43040.4 44055.6 44618.8 45041.3 56148.7 60699.5 61836.0 62350.7 62494.0 9486.7 9522.7 9707.2 9768.4 9882.0 10069.9 10070.0 10191.9 10416.9 10472.9 9272.6 9367.6 9418.2 9637.1 9702.8 9401.4 9513.4 9606.7 9742.2 9873.5 9315.4 9347.6 9349.3 9522.2 9649.4 8038.8 8111.9 8382.0 8449.4 8459.8 7553.4 7743.9 7949.5 8161.4 8328.6 17:42:58 INFO - PROCESS | 1938 | -------- Summary: end -------- 17:42:58 INFO - PROCESS | 1938 | 17:42:59 INFO - TEST-INFO | 1938: exit 0 17:42:59 INFO - TEST-OK | dromaeo_css | took 1116053ms 17:42:59 INFO - TEST-START | kraken 17:42:59 INFO - operating with platform_type : mac_ 17:42:59 INFO - Initialising browser for kraken test... 17:42:59 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpATDaTW/profile http://localhost:50804/getInfo.html) 17:43:01 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 17:43:01 INFO - PROCESS | 1944 | colorDepth:24 17:43:01 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 17:43:01 INFO - PROCESS | 1944 | __metrics 17:43:01 INFO - PROCESS | 1944 | 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" 17:43:01 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:43:02 INFO - TEST-INFO | 1944: exit 0 17:43:02 INFO - Browser initialized. 17:43:02 INFO - Running cycle 1/1 for kraken test... 17:43:02 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpATDaTW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:43:14 INFO - PROCESS | 1945 | RSS: Main: 241061888 17:43:14 INFO - PROCESS | 1945 | 17:43:17 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50804/tests/kraken/driver.html?testName=audio-beat-detection) 17:43:17 INFO - PROCESS | 1945 | RSS: Main: 288780288 17:43:17 INFO - PROCESS | 1945 | 17:43:20 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50804/tests/kraken/driver.html?testName=audio-dft) 17:43:21 INFO - PROCESS | 1945 | RSS: Main: 297431040 17:43:21 INFO - PROCESS | 1945 | 17:43:24 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50804/tests/kraken/driver.html?testName=audio-fft) 17:43:25 INFO - PROCESS | 1945 | RSS: Main: 385720320 17:43:25 INFO - PROCESS | 1945 | 17:43:27 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50804/tests/kraken/driver.html?testName=audio-oscillator) 17:43:28 INFO - PROCESS | 1945 | RSS: Main: 286715904 17:43:28 INFO - PROCESS | 1945 | 17:43:30 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50804/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:43:31 INFO - PROCESS | 1945 | RSS: Main: 292114432 17:43:31 INFO - PROCESS | 1945 | 17:43:33 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50804/tests/kraken/driver.html?testName=imaging-darkroom) 17:43:34 INFO - PROCESS | 1945 | RSS: Main: 328671232 17:43:34 INFO - PROCESS | 1945 | 17:43:37 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50804/tests/kraken/driver.html?testName=imaging-desaturate) 17:43:37 INFO - PROCESS | 1945 | RSS: Main: 328351744 17:43:37 INFO - PROCESS | 1945 | 17:43:40 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50804/tests/kraken/driver.html?testName=json-parse-financial) 17:43:41 INFO - PROCESS | 1945 | RSS: Main: 330379264 17:43:41 INFO - PROCESS | 1945 | 17:43:42 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50804/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:43:43 INFO - PROCESS | 1945 | RSS: Main: 315461632 17:43:43 INFO - PROCESS | 1945 | 17:43:45 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:43:46 INFO - PROCESS | 1945 | RSS: Main: 322437120 17:43:46 INFO - PROCESS | 1945 | 17:43:48 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:43:49 INFO - PROCESS | 1945 | RSS: Main: 324755456 17:43:49 INFO - PROCESS | 1945 | 17:43:52 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:43:52 INFO - PROCESS | 1945 | RSS: Main: 329576448 17:43:52 INFO - PROCESS | 1945 | 17:43:55 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:43:56 INFO - PROCESS | 1945 | RSS: Main: 327225344 17:43:56 INFO - PROCESS | 1945 | 17:43:57 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:50804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50804/tests/kraken/driver.html?testName=ai-astar) 17:43:58 INFO - PROCESS | 1945 | RSS: Main: 373755904 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | __start_tp_report 17:43:58 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 17:43:58 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 17:43:58 INFO - PROCESS | 1945 | |i|pagename|runs| 17:43:58 INFO - PROCESS | 1945 | |0;ai-astar;112;107;112;112;111;112;112;113;112;116 17:43:58 INFO - PROCESS | 1945 | |1;audio-beat-detection;153;121;110;173;119;127;137;115;146;119 17:43:58 INFO - PROCESS | 1945 | |2;audio-dft;184;184;195;231;260;209;182;184;183;183 17:43:58 INFO - PROCESS | 1945 | |3;audio-fft;79;58;64;131;59;76;84;78;84;57 17:43:58 INFO - PROCESS | 1945 | |4;audio-oscillator;94;72;114;84;94;99;126;101;68;100 17:43:58 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;88;86;88;86;93;89;91;85;88;84 17:43:58 INFO - PROCESS | 1945 | |6;imaging-darkroom;93;94;94;94;93;94;94;95;93;103 17:43:58 INFO - PROCESS | 1945 | |7;imaging-desaturate;91;87;87;86;84;84;86;86;84;88 17:43:58 INFO - PROCESS | 1945 | |8;json-parse-financial;56;56;58;56;58;58;56;73;57;55 17:43:58 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;65;71;68;75;74;75;70;93;101;101 17:43:58 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;86;86;87;85;86;86;99;107;106;86 17:43:58 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;144;145;146;138;141;138;140;141;184;153 17:43:58 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;170;163;163;165;166;165;164;166;168;162 17:43:58 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;67;66;67;67;67;66;66;65;67;67 17:43:58 INFO - PROCESS | 1945 | __end_tp_report 17:43:58 INFO - PROCESS | 1945 | __start_cc_report 17:43:58 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1976 17:43:58 INFO - PROCESS | 1945 | __end_cc_report 17:43:58 INFO - PROCESS | 1945 | __startTimestamp1464223438052__endTimestamp 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | ------- Summary: start ------- 17:43:58 INFO - PROCESS | 1945 | Number of tests: 14 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:2.18 (1.9%) stddev-sans-first:2.32 17:43:58 INFO - PROCESS | 1945 | Values: 112.0 107.0 112.0 112.0 111.0 112.0 112.0 113.0 112.0 116.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:132.00 stddev:20.00 (15.2%) stddev-sans-first:19.72 17:43:58 INFO - PROCESS | 1945 | Values: 153.0 121.0 110.0 173.0 119.0 127.0 137.0 115.0 146.0 119.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:199.50 Median:189.50 stddev:26.53 (14.0%) stddev-sans-first:27.54 17:43:58 INFO - PROCESS | 1945 | Values: 184.0 184.0 195.0 231.0 260.0 209.0 182.0 184.0 183.0 183.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:77.00 Median:77.00 stddev:21.79 (28.3%) stddev-sans-first:23.10 17:43:58 INFO - PROCESS | 1945 | Values: 79.0 58.0 64.0 131.0 59.0 76.0 84.0 78.0 84.0 57.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:78.00 stddev:17.57 (22.5%) stddev-sans-first:18.63 17:43:58 INFO - PROCESS | 1945 | Values: 94.0 72.0 114.0 84.0 94.0 99.0 126.0 101.0 68.0 100.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.00 stddev:2.74 (3.1%) stddev-sans-first:2.91 17:43:58 INFO - PROCESS | 1945 | Values: 88.0 86.0 88.0 86.0 93.0 89.0 91.0 85.0 88.0 84.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.00 stddev:2.98 (3.2%) stddev-sans-first:3.10 17:43:58 INFO - PROCESS | 1945 | Values: 93.0 94.0 94.0 94.0 93.0 94.0 94.0 95.0 93.0 103.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:86.50 stddev:2.16 (2.5%) stddev-sans-first:1.48 17:43:58 INFO - PROCESS | 1945 | Values: 91.0 87.0 87.0 86.0 84.0 84.0 86.0 86.0 84.0 88.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:57.50 stddev:5.27 (9.2%) stddev-sans-first:5.53 17:43:58 INFO - PROCESS | 1945 | Values: 56.0 56.0 58.0 56.0 58.0 58.0 56.0 73.0 57.0 55.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:72.50 stddev:13.67 (18.9%) stddev-sans-first:13.49 17:43:58 INFO - PROCESS | 1945 | Values: 65.0 71.0 68.0 75.0 74.0 75.0 70.0 93.0 101.0 101.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:86.00 stddev:8.95 (10.4%) stddev-sans-first:9.27 17:43:58 INFO - PROCESS | 1945 | Values: 86.0 86.0 87.0 85.0 86.0 86.0 99.0 107.0 106.0 86.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:144.50 stddev:13.75 (9.5%) stddev-sans-first:14.54 17:43:58 INFO - PROCESS | 1945 | Values: 144.0 145.0 146.0 138.0 141.0 138.0 140.0 141.0 184.0 153.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.50 stddev:2.44 (1.5%) stddev-sans-first:1.87 17:43:58 INFO - PROCESS | 1945 | Values: 170.0 163.0 163.0 165.0 166.0 165.0 164.0 166.0 168.0 162.0 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 17:43:58 INFO - PROCESS | 1945 | Values: 67.0 66.0 67.0 67.0 67.0 66.0 66.0 65.0 67.0 67.0 17:43:58 INFO - PROCESS | 1945 | -------- Summary: end -------- 17:43:58 INFO - PROCESS | 1945 | 17:43:58 INFO - TEST-INFO | 1945: exit 0 17:43:59 INFO - TEST-OK | kraken | took 59573ms 17:43:59 INFO - SUITE-END | took 1175s 17:43:59 INFO - Completed test suite (00:19:35) 17:43:59 INFO - javascript benchmark 17:43:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3394.81328526931, 3404.9785064480648, 3410.7953522788634, 3414.2147306119577, 3469.670381313776, 5486.61593688442, 5511.311086114236, 5532.5573954083675, 5560.972195139028, 5590.077637189869, 5548.140038294071, 5590.217369568253, 5642.520385767215, 5664.461876121762, 5701.77192912284, 5018.04657115147, 5056.469070747556, 5107.693538387709, 5112.054269960051, 5211.296474975868, 5163.63854530183, 5184.922226166611, 5199.012187684328, 5219.164933610623, 5238.109521381374, 5026.1204289249235, 5088.949110508885, 5092.490750924915, 5093.236014597803, 5159.71621560814, 5129.974005198937, 5139.229115632647, 5147.819826306099, 5151.793928242866, 5162.509561591681, 5055.545000949895, 5078.847634570969, 5080.13637681595, 5089.8727531812, 5096.388433388017, 5439.184122381635, 5459.317585301837, 5497.780088796443, 5507.449255074461, 5518.255035570187, 6090.056041313604, 6116.96941515294, 6164.660943648098, 6169.5064394848305, 6174.938250617482, 5601.943980560183, 6097.4036925702085, 6217.285012223607, 6224.813255602339, 6237.407446292595, 5645.633033852787, 5659.886802263932, 5687.687177205211, 5759.971200143972, 5774.39368866263, 6281.152044473937, 6290.968545157337, 6294.8111556653375, 6306.810795676137, 6316.778912738032, 5871.735771890275, 5939.13882487037, 6035.547333949971, 6070.9870567032185, 6086.756759456233, 6114.449440885012, 6173.876522469615, 6179.073139029092, 6188.81433556994, 6189.319174890758, 6098.664573448503, 6103.755849766048, 6118.405621347521, 6128.044828652003, 6136.917362303726, 5806.767729290866, 5985.16207730918, 6037.245344331958, 6112.777056696867, 6134.840557113091, 4089.488813898263, 4794.2808578713475, 4836.3712717347225, 4841.419029716457, 4862.027594481047, 4315.546867578922, 4318.784060796947, 4350.543192964693, 4353.6081752641785, 4359.716618419792, 5502.0921547944945, 5598.524125449382, 5617.494425501724, 5622.128570071645, 5635.802746903921, 5224.190250511177, 6114.755409783559, 6190.102435146879, 6205.100260462208, 6208.068789681584, 5614.270144881221, 5623.718814059281, 5625.943740562624, 5650.773969041193, 5653.349864765604, 5199.480051994846, 5249.396319423239, 5251.711155886463, 5288.286081379041, 5294.867628309323, 6201.286852012076, 6217.595856269328, 6258.186435763321, 6271.843203919939, 6278.591891527036, 6094.592115505723, 6194.5044396449075, 6196.0, 6197.194364732556, 6209.130721698875, 5627.7163789891965, 6145.0782382642965, 6212.223472065992, 6264.310925798068, 6274.686265686789, 5951.315598706205, 5954.970225148846, 6067.120267561268, 6092.964196086587, 6103.633781972918, 6081.148639190428, 6118.541109416722, 6118.724657390339, 6121.418465245794, 6131.570790044654, 4993.251012348177, 5812.21535092774, 5822.0, 5874.206982057539, 5880.588358814842, 5948.553858460719, 5969.791057312973, 5970.402959703995, 5973.342932277359, 6003.87992240144, 5763.682997435181, 5771.624844385102, 5787.0, 5805.8548536286935, 5858.179854820243, 5691.715414229355, 5864.208331875142, 5952.851178720567, 5956.374580668968, 5972.193753843176, 5739.110437882135, 5829.941700583109, 5848.093545500455, 5853.882922341614, 5855.882882342415], "value": 5583.950747068755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.1012622846903, 514.1003244322436, 517.4695936664918, 529.4837533404929, 533.2135100726427, 35022.64977350226, 41956.0, 42145.15709685804, 43754.0, 43771.3434298485, 42956.0, 43663.0, 43677.56322436775, 43706.562934370646, 43890.78054609722, 8619.267362274215, 8810.38327317088, 9539.284553658468, 9757.707268781913, 11815.409229538489, 9303.581338839735, 11560.075193984461, 11873.346965916871, 11899.940500297529, 12231.938840305786, 11849.407529623528, 11930.0, 11943.223690460092, 11954.103442241823, 11966.222195557304, 8614.483131012159, 11548.769024619503, 11747.647570572897, 11754.706132346675, 12025.338606376647, 11724.824127638092, 11804.822927656092, 11816.0, 11825.290482570987, 11846.229995050295, 40005.59994400048, 40400.39399409011, 40409.797951010354, 40427.989300267436, 40480.797596012126, 3190.0429871038828, 3337.732981361485, 3374.2745309758275, 3474.2356681530023, 3599.748017638766, 24379.878100609385, 28474.288142796595, 28561.428771424664, 30119.69880301191, 30210.0, 2235.5193633368904, 2287.4167087392775, 2758.3793646429394, 2772.625695531098, 2796.734310240524, 429776.0, 515139.0, 515902.0, 517137.8286217165, 517586.0, 60591.39408605946, 60622.69688651617, 61132.69433652803, 61151.0, 61182.0, 64397.35602644007, 64408.0, 64412.0, 64555.03167452492, 64558.0, 14695.412183512753, 16149.273282702306, 16318.020918744676, 16343.673126537402, 16355.42756003558, 11653.941730291293, 12711.872881271069, 12869.485220591072, 12997.805032924514, 13002.739945201232, 16165.030098194145, 16360.263788129563, 16399.42602008924, 16411.0, 16518.42185523501, 6172.382761723792, 7393.260673932671, 7422.925770742331, 7482.139553951257, 7609.0, 882.1751662195867, 990.9009099090177, 1031.7111208861531, 1043.9373637581618, 1066.1204506282347, 25845.74154258471, 28248.71751282461, 28323.008694695585, 29009.0, 29243.976460823767, 1072.4316112460408, 1078.3907092492716, 1098.368438148068, 1110.7334239782556, 1134.8014097533064, 13796.793048104286, 14686.339114739862, 14763.40946362155, 14772.852271477363, 15064.774028389584, 610440.0, 611979.0, 615869.0, 617129.0, 622525.0, 57498.425015750145, 57514.71242643844, 57679.13481297784, 57847.0, 57879.42120578825, 1288.9419976101099, 1405.6134562995053, 1438.3527412664134, 1445.8409574946747, 1447.6887469194173, 29773.255668608457, 30490.542641859945, 30619.387612248076, 30760.846195768878, 31225.68774312228, 59149.0, 63116.68441657762, 63737.36262637314, 63753.681231593546, 63798.0, 641524.7923760351, 650044.0, 651358.0, 651556.0, 652659.0, 36787.44818827666, 46268.07463850637, 47331.29003064888, 47555.76222118867, 47681.523184769096, 47987.0, 48428.273575895684, 48598.27102593393, 48753.75623121862, 48774.512254876994, 38062.0, 39346.52531221338, 39656.60343396529, 39666.0, 40611.78164655065, 35196.82401588078, 38798.6120138795, 39034.6096539031, 39871.40192897167, 39912.60087399089, 38150.09249537456, 38840.6115938837, 39510.80244598874, 39511.81464556068, 39521.80239098786, 31417.685823141477, 32523.0, 34169.241383447305, 34254.65745342515, 34299.0, 24651.50696986086, 24976.0, 25181.87409062943, 25496.0, 25654.61518077193], "value": 20694.982675585125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.36057325126916, 386.4377330983419, 390.0560643243349, 393.10176247274984, 414.9958500414995, 13152.473901043946, 20181.19275228989, 20507.282245121423, 20950.790492095075, 20969.895150524226, 15410.537683869474, 16687.33250669975, 19490.512737181576, 20306.796932030677, 20479.0, 5636.18275350074, 5790.0, 5841.831633673261, 5852.356240813507, 5852.356240813507, 5555.777768889239, 5655.69918918649, 5759.107338362561, 5796.82609521715, 5813.30240371156, 5701.828945131632, 5723.399043100456, 5731.283589551306, 5731.684757338345, 5740.741666625012, 5391.703456309881, 5677.744501497443, 5679.886402271973, 5722.313322401317, 5725.227094342273, 4968.2299243616935, 4984.750762461862, 4988.900221995576, 4990.65065445419, 4991.001799640087, 23758.40603984897, 23796.92913818882, 25144.0, 25260.0, 25264.11575594864, 2072.212559227484, 2077.2833372486466, 2319.211468100837, 2396.664466974625, 2405.867677277749, 14770.926145369313, 14843.480478183319, 14902.850971490254, 14992.550223493312, 15228.314725837365, 1646.3743777364525, 1754.0440459949232, 1764.2061072517417, 1787.892726436418, 1854.4992851930037, 83811.5809420949, 105275.0, 106340.0, 108050.0, 108235.91764082338, 9256.768580785534, 10323.793524129474, 10340.586376544854, 10465.738356541147, 10770.730731731614, 13520.59438216855, 13600.86399135996, 13601.659958500919, 13622.318884055758, 13634.318284085755, 5999.5500337473995, 6192.938070619326, 7089.397401220954, 7713.344365728976, 7807.102183248886, 6572.474202063823, 7543.321101100927, 7618.428617853683, 7701.691932322644, 7708.49894756839, 5331.333583302087, 6353.936460635335, 6505.466904545968, 6507.7071531781185, 6569.0, 1609.3803885504165, 1874.5969616532511, 1878.4270797406648, 1894.2233684189691, 1923.086533896416, 826.9338452923752, 860.0238729042582, 915.9924083508088, 927.3137877970388, 951.5813042261383, 18697.719534206997, 19140.712889306393, 19322.0, 19480.415587532396, 19670.0, 891.7057371067532, 892.2727976698969, 901.3420203251532, 919.6643225222746, 927.596495524449, 9332.72001839946, 10198.33710808795, 10466.162706983423, 10482.68551943443, 10489.318194317346, 570194.0, 575656.0, 586242.0, 586588.0, 589992.0, 79080.0, 111624.0, 117054.8294517044, 117170.0, 117846.0, 964.4695417520336, 973.6105557776946, 999.2954966748425, 1022.4274406332478, 1023.5291765787821, 17095.401660941807, 17241.39655112065, 17430.651386972444, 17559.824401755817, 17594.82405175932, 49042.75478622584, 50559.74720126376, 50636.49363506465, 50790.0, 51397.0, 70979.0, 71024.93462598168, 71798.64100679463, 72290.63854680693, 73053.90419143612, 7695.076590809139, 7799.0, 7819.530828150329, 7853.56805375687, 8237.79405514867, 12183.208091474025, 12443.875561244633, 12747.426365813375, 12786.36068196605, 12836.03729720256, 9770.36492627977, 10198.286119971532, 10490.160787137163, 10924.672259832218, 10932.726681832699, 7830.491018083806, 8540.74377768668, 10623.0, 10754.300970436896, 10782.514786834769, 10796.622118225825, 10893.945530272298, 11135.554577816796, 11433.1996760226, 11556.826647600461, 6628.734850605922, 7998.240167184108, 8032.51804891685, 8202.507849529047, 8457.238848503664, 8943.865842012245, 10508.42203678805, 10508.842367364343, 10717.571297148026, 10738.463076846283], "value": 9217.905155148963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.1431098408002, 1589.9443519476818, 1613.4433620400962, 1867.3557622620192, 2001.859869809113, 7757.301842834151, 8622.91377086229, 8868.778280542982, 8872.512011839362, 9000.954995225014, 7120.857582848353, 8817.4709517429, 8825.205731484166, 8833.558322083902, 8837.293016558677, 1953.7460130183056, 2122.554263604645, 2192.1121945612053, 2366.2546297916147, 2404.0263693204192, 2420.685310909588, 2453.509298140379, 2457.336519139831, 2457.373369790701, 2457.938551536217, 2371.537550177716, 2399.892004859777, 2409.1929203716777, 2420.5037967216676, 2421.116292553223, 2093.2150443583655, 2171.3811563704267, 2174.521605246843, 2177.2597316912324, 2366.6804981327473, 2085.384811480617, 2347.1784875293683, 2426.478307163968, 2436.549238390903, 2437.427204606916, 8736.51949142797, 8823.382363234576, 8824.602892869836, 8830.55847207637, 8834.86747698785, 5245.580353571743, 5400.405955344909, 5421.647592906448, 5427.592930530226, 5432.158015507603, 3390.593128824532, 3508.4036763272907, 3569.6787289144104, 3582.0, 3584.462330650423, 990.4057565460666, 1003.2325271167563, 1004.7689031522717, 1005.8742657167854, 1011.0294117647068, 11698.181127321006, 11749.823752643717, 11750.999999999915, 11751.764964700658, 11776.528938842435, 4699.812007519729, 4725.243960966298, 4849.175640141114, 4855.1018061658, 4863.148948933993, 9937.354071985297, 9937.503124843728, 9941.40351578893, 9942.751431214278, 9980.60077596888, 5332.440093790174, 5409.323834520685, 5424.647397919122, 5424.83725488236, 5424.891502169935, 4189.979050104791, 4423.181711383404, 4589.908201835945, 4775.95224047762, 4926.531979461945, 3925.175713100281, 3997.84008639657, 4044.919101617951, 4068.2880495913096, 4093.85671501496, 1099.9890001100046, 1296.9805452918213, 1299.1807777123665, 1308.9738205236033, 1311.8360204974379, 844.8720957521655, 851.2764150472049, 889.9110088991179, 896.3949334199519, 930.9534523273809, 5713.571482138773, 5721.828345149652, 5838.970805145947, 5880.8235752927485, 5916.763329466859, 902.4449963272672, 907.2605826251583, 907.7458311672742, 923.9214666753401, 976.9364991275544, 3836.8848934532007, 3920.3923391874305, 3937.9803100984313, 3982.323005089084, 3987.6211759882763, 10339.896601034045, 10538.631147909786, 10690.839637405445, 10704.411257380918, 10709.732256693489, 10623.575056997634, 10645.680629581126, 10702.0, 10716.142708583295, 10728.356298621951, 8062.113167551682, 8151.918480815235, 8156.306713929383, 8287.58562071906, 8296.377771667148, 5560.4161563035295, 5731.627444216113, 5754.827355179351, 5774.05523183188, 5799.246098007232, 9628.32601717873, 10041.598336066476, 10061.0, 10076.345037572535, 10094.697159085239, 9158.313126515672, 9555.140037396668, 10180.898191017995, 10220.182385409298, 10241.0, 6310.747570097328, 6467.7736279231885, 6670.099536562502, 6968.059311992816, 7100.076989991268, 7770.766876993699, 7836.764897053097, 7842.901993720998, 7850.6074696266105, 7866.8819967701675, 7215.567065976058, 7300.452466065172, 7322.597257150908, 7446.515976461729, 7475.177730449537, 7228.891566626399, 7238.986541884035, 7319.377852882564, 7418.591977441292, 7453.962730186315, 6766.255711871789, 6802.557833740791, 6882.793516194522, 6892.483063770137, 6893.482988775761, 4040.6363427290507, 4655.0, 4847.200211965127, 5018.121828679893, 5119.92320115206, 4130.504339479282, 4302.892427689333, 4445.266531022345, 4578.244589642805, 4633.328167415773], "value": 4521.354826304794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.3183382374944, 300.253869135199, 301.4167585721629, 317.36526946107784, 318.4029943855271, 4925.793180670732, 5074.361332107302, 5161.458046905077, 5176.223566465027, 5238.035736598776, 4222.831086756533, 4888.80224345036, 5012.373453318335, 5093.872653183672, 5102.953894451638, 3109.782315237934, 3691.963080369202, 3996.4804575405155, 4003.1993601279714, 4043.2115737431286, 3946.3883098119695, 4012.779297138656, 4020.8540565938715, 4021.6380525752675, 4043.130726893717, 3768.868089616864, 3811.4282857571447, 3957.6042395760337, 3971.6028397160344, 4083.7958102094776, 3069.539569064647, 3831.0, 3970.463987361698, 3975.6819454443867, 3994.9001274968214, 3254.6908043735807, 3523.3834079036064, 3530.1527633368064, 3531.7527773055895, 3542.2030043240325, 4192.769397683126, 4933.7286449245275, 5022.296878437021, 5025.497450254982, 5037.194048952187, 4539.251023581105, 4581.473130589991, 4584.289435137559, 4605.792739326705, 4607.0, 5021.9748901255625, 5147.305113809625, 5461.777551244784, 5474.7262636868, 5498.140966697335, 3157.952630710541, 3877.379619260933, 3891.5524714657895, 3909.58949310324, 3910.042039700263, 5228.8757917048015, 5231.947680523184, 5353.491418315254, 5729.997250481191, 5795.710214489259, 3246.610406751205, 3537.4870643756512, 3789.886303410902, 3810.561785394715, 3949.913773712252, 3340.365330587181, 3788.507494025814, 3998.5005622891417, 4008.6993475489453, 4018.3524754850373, 4179.26862799009, 4376.212281789245, 4561.977190114028, 4616.492185859553, 4637.634806248838, 5246.291750613695, 5480.534154596904, 5481.643693159932, 5496.477834605706, 5515.862103447446, 1180.710725872167, 1209.1017103147974, 1367.3436750359883, 1378.4141739760562, 1383.633337165652, 843.1526316052328, 864.5720368417674, 869.2958703450225, 885.7112900729423, 893.1871996483299, 4964.751762411865, 5056.494350564988, 5063.316452278895, 5067.7837319043165, 5118.822670785666, 4621.792019359137, 5144.6398752087, 5427.294451721251, 5531.708633363223, 5607.644789188551, 4466.263066593976, 4727.409073865767, 4827.734474603861, 5006.374203224597, 5009.924851127236, 2912.3156058326276, 2953.7446585201205, 2970.3465237647683, 2970.6928951261807, 2982.492976194052, 5408.675479471244, 5491.807786727525, 5849.590528662953, 5897.2038774765715, 5931.881362372728, 4759.547842954914, 4781.569658730731, 5726.850232372179, 5856.795012174639, 5876.265466816648, 5156.767945442464, 5188.186682788072, 5507.366652834975, 5759.65442073477, 6053.303870055, 5035.370578677665, 5323.920141197807, 5696.829095127153, 5698.2592263005545, 5795.623284486495, 4503.617192538671, 5337.786488540415, 5611.6352437091455, 5630.95827271956, 5708.172315014383, 4721.787519561559, 5231.633785634969, 5483.533899618417, 5519.39286678473, 5899.823005309848, 3266.754993375554, 3515.1878497201624, 3615.439606860941, 3782.905427364338, 3864.632859878307, 4640.675152739276, 5394.595405344537, 5701.572382071444, 5719.6272894505855, 5726.9691455538405, 5069.467705890828, 5280.841574752763, 5316.255724198692, 5515.531179849597, 5525.4474552544425, 5501.0, 5511.53151982086, 5513.751881165277, 5542.61201715876, 5597.944020559742, 4358.951292892336, 4886.971906658839, 5005.674631148963, 5382.650127741685, 5389.865253368697, 4277.593628605277, 4516.841910533116, 4715.575598196178, 5173.92239116406, 5561.804212094419, 4702.777277907837, 5407.215953686772, 5444.346678398618, 5466.043442397485, 5551.528120109674], "value": 3987.135525815579, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.199948062844, 711.6939715922155, 719.7480881691407, 721.5598484924195, 776.4359118918333, 14237.0, 14838.703225935502, 14866.108033517969, 14873.553793386189, 14874.628134296649, 11419.0, 13918.0, 13943.442262309496, 13965.371558279876, 13985.650358741037, 653.157426919276, 686.1663079358578, 880.6301353431542, 897.3135551303258, 898.6225785170244, 850.336737344872, 899.7435730816718, 900.2932697832204, 900.8063266397724, 901.1709227510706, 938.0994245524305, 951.4719330771419, 951.8477043673014, 952.4666186935339, 953.2421724728857, 728.6429649471721, 899.7390756680555, 944.9385789923633, 946.1059298962483, 946.2524605561598, 706.388973537886, 706.9081019467487, 706.960767671522, 708.1926603671818, 708.7979925721145, 14564.126152430783, 14592.0, 14618.707625847423, 14625.0, 14661.926690366587, 12012.939935300268, 12246.81629775554, 12265.509379624804, 12276.693082672915, 12385.504579816796, 10593.470326483723, 10640.57437702491, 10755.946220268848, 10968.903109688967, 11023.338599684044, 3502.842372093262, 3996.7002474814212, 3999.6600288975474, 4039.2931237033695, 4140.60664236897, 73899.63050184769, 75020.0, 76264.61867690682, 76274.23725762783, 76647.0, 9294.0, 9319.627214911463, 9573.760655983657, 9608.087231712976, 9627.326087173831, 16928.661426771643, 21415.678764818542, 21416.143354265656, 21433.67849482259, 21439.892800535898, 6116.0, 7153.642317884085, 7169.856602868018, 7300.087489063867, 7338.082739657543, 3836.731428800013, 3939.153082087365, 4002.5597184309704, 4012.398140278923, 4015.77913214776, 3361.9831900840827, 3462.290230502741, 3464.826758662057, 3467.531883195736, 3468.1849765305133, 599.8320470268332, 614.5790133758389, 616.1497133955113, 618.7494064903722, 620.7765204526457, 465.75780594090884, 501.97992080317107, 510.32891747352113, 518.6758276077452, 554.3790954131399, 6509.349065093453, 7365.852682946311, 7561.508501947355, 7580.924190758132, 7643.617819109022, 466.2097744323339, 509.16242780625663, 514.729766872395, 525.4036668381415, 528.9576833853282, 3307.371599396155, 3351.4470112431663, 3453.8273086346085, 3545.18460754028, 3733.570639376452, 84794.57602711947, 86248.0, 88163.0, 88179.67730484047, 88824.0, 74708.62645686737, 74850.625746872, 75200.87198692025, 75649.62175189199, 76134.0, 65836.67081664561, 68813.65593172002, 69699.0, 69817.65091174512, 70085.0, 5654.604177707521, 6939.340762627744, 7027.859442811013, 7124.358807707331, 7337.3029562191505, 42632.0, 43040.35439468473, 44055.559444405146, 44618.776906115265, 45041.32438013367, 56148.719256403456, 60699.48251293753, 61836.0, 62350.68824655848, 62494.0, 9486.715398538056, 9522.714318570454, 9707.174890134134, 9768.36505627132, 9882.0, 10069.89930100719, 10069.993000699871, 10191.949040255047, 10416.947915260374, 10472.947635261775, 9272.582733777132, 9367.578458969227, 9418.199453046567, 9637.132658060807, 9702.757431063998, 9401.435913845191, 9513.429194248367, 9606.711798646053, 9742.171915387271, 9873.50632468388, 9315.441073535338, 9347.579358928728, 9349.298802589698, 9522.190613797904, 9649.372790768577, 8038.758837234892, 8111.878321825059, 8381.960757048344, 8449.366297527833, 8459.788505287417, 7553.395728341829, 7743.883841742266, 7949.523028618301, 8161.428699990944, 8328.583570821556], "value": 6429.157572154985, "unit": "score"}], "name": "dromaeo_css", "value": 7056.596376123679, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 112.0, 112.0, 111.0, 112.0, 112.0, 113.0, 112.0, 116.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 110.0, 173.0, 119.0, 127.0, 137.0, 115.0, 146.0, 119.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 195.0, 231.0, 260.0, 209.0, 182.0, 184.0, 183.0, 183.0], "value": 199.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 64.0, 131.0, 59.0, 76.0, 84.0, 78.0, 84.0, 57.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 72.0, 114.0, 84.0, 94.0, 99.0, 126.0, 101.0, 68.0, 100.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 93.0, 89.0, 91.0, 85.0, 88.0, 84.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 93.0, 103.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 87.0, 86.0, 84.0, 84.0, 86.0, 86.0, 84.0, 88.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 56.0, 58.0, 58.0, 56.0, 73.0, 57.0, 55.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 68.0, 75.0, 74.0, 75.0, 70.0, 93.0, 101.0, 101.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 85.0, 86.0, 86.0, 99.0, 107.0, 106.0, 86.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 146.0, 138.0, 141.0, 138.0, 140.0, 141.0, 184.0, 153.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 163.0, 165.0, 166.0, 165.0, 164.0, 166.0, 168.0, 162.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0, 67.0, 67.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1492.1, "alertThreshold": 2.0}]} 17:43:59 INFO - Return code: 0 17:43:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:43:59 ERROR - # TBPL SUCCESS # 17:43:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:43:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:43:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:43:59 INFO - 2016-05-25 17:43:59.362 screenresolution[1949:13988] starting screenresolution argv=screenresolution get 17:43:59 INFO - 2016-05-25 17:43:59.365 screenresolution[1949:13988] Display 0: 1600x1200x32@60 17:43:59 INFO - 2016-05-25 17:43:59.371 screenresolution[1950:13991] starting screenresolution argv=screenresolution list 17:43:59 INFO - Available Modes on Display 0 17:43:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:43:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:43:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:43:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:43:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:43:59 INFO - Intel Iris: 17:43:59 INFO - Chipset Model: Intel Iris 17:43:59 INFO - Type: GPU 17:43:59 INFO - Bus: Built-In 17:43:59 INFO - VRAM (Dynamic, Max): 1536 MB 17:43:59 INFO - Vendor: Intel (0x8086) 17:43:59 INFO - Device ID: 0x0a2e 17:43:59 INFO - Revision ID: 0x0009 17:43:59 INFO - Displays: 17:43:59 INFO - Display: 17:43:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:43:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:43:59 INFO - Display Serial Number: 1600x1200 60 17:43:59 INFO - Main Display: Yes 17:43:59 INFO - Mirror: Off 17:43:59 INFO - Online: Yes 17:43:59 INFO - Rotation: Supported 17:43:59 INFO - Return code: 0 17:43:59 INFO - Running post-action listener: _resource_record_post_action 17:43:59 INFO - ##### 17:43:59 INFO - ##### Finished run-tests step (success) 17:43:59 INFO - ##### 17:43:59 INFO - Running post-run listener: _resource_record_post_run 17:43:59 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1085440; Write bytes: 692659712; Read time: 13; Write time: 1878 17:43:59 INFO - TinderboxPrint: CPU usage
53.6% 17:43:59 INFO - TinderboxPrint: I/O read bytes / time
1,085,440 / 13 17:43:59 INFO - TinderboxPrint: I/O write bytes / time
692,659,712 / 1,878 17:43:59 INFO - TinderboxPrint: CPU idle
2,248.7 (46.4%) 17:43:59 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 17:43:59 INFO - TinderboxPrint: CPU user
1,628.2 (33.6%) 17:43:59 INFO - TinderboxPrint: Swap in / out
398,503,936 / 0 17:43:59 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397275648; Write bytes: 391628288; Read time: 29983; Write time: 774 17:43:59 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1085440; Write bytes: 292020224; Read time: 13; Write time: 1071 17:44:00 INFO - Running post-run listener: _upload_blobber_files 17:44:00 INFO - Blob upload gear active. 17:44:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:44:00 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 17:44:00 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'] 17:44:00 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 17:44:00 INFO - (blobuploader) - INFO - Open directory for files ... 17:44:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:44:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:44:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:01 INFO - (blobuploader) - INFO - Done attempting. 17:44:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:44:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:44:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:02 INFO - (blobuploader) - INFO - Done attempting. 17:44:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:44:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:44:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:04 INFO - (blobuploader) - INFO - Done attempting. 17:44:04 INFO - (blobuploader) - INFO - Iteration through files over. 17:44:04 INFO - Return code: 0 17:44:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:44:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:44:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c46269a93dea6bbec8987e96283780554d7006dbca44f960797c04ab9d3a1345d524106a7ef1ceee5cf0f12f07db63a77897e650130fa69e04b4ad5a985b472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8da6dc1d401da0aa99b55921744611d090ea753bf65d04dbc229ae0fbaf1df5b741acbcba381806b7b9b7947ed937ad2db573afdde454dca467cfe0ce5d8851", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d7041e78daca4e3a9be8628ef1ee62a739ceefde739cccf5f278a2d8a4c5d1130376547e74e7b41384172a065016856388fe3082f9b7e728404d0950f6e59b2"} 17:44:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:44:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:44:04 INFO - Contents: 17:44:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c46269a93dea6bbec8987e96283780554d7006dbca44f960797c04ab9d3a1345d524106a7ef1ceee5cf0f12f07db63a77897e650130fa69e04b4ad5a985b472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8da6dc1d401da0aa99b55921744611d090ea753bf65d04dbc229ae0fbaf1df5b741acbcba381806b7b9b7947ed937ad2db573afdde454dca467cfe0ce5d8851", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d7041e78daca4e3a9be8628ef1ee62a739ceefde739cccf5f278a2d8a4c5d1130376547e74e7b41384172a065016856388fe3082f9b7e728404d0950f6e59b2"} 17:44:04 INFO - Running post-run listener: copy_logs_to_upload_dir 17:44:04 INFO - Copying logs to upload dir... 17:44:04 INFO - mkdir: /builds/slave/test/build/upload/logs 17:44:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.738085 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-25 17:44:05.509757) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 17:44:05.518297) ========= 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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/3c46269a93dea6bbec8987e96283780554d7006dbca44f960797c04ab9d3a1345d524106a7ef1ceee5cf0f12f07db63a77897e650130fa69e04b4ad5a985b472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8da6dc1d401da0aa99b55921744611d090ea753bf65d04dbc229ae0fbaf1df5b741acbcba381806b7b9b7947ed937ad2db573afdde454dca467cfe0ce5d8851", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d7041e78daca4e3a9be8628ef1ee62a739ceefde739cccf5f278a2d8a4c5d1130376547e74e7b41384172a065016856388fe3082f9b7e728404d0950f6e59b2"} build_url:https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010930 build_url: 'https://queue.taskcluster.net/v1/task/EpY0XcafTIynQLopphfbuA/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/3c46269a93dea6bbec8987e96283780554d7006dbca44f960797c04ab9d3a1345d524106a7ef1ceee5cf0f12f07db63a77897e650130fa69e04b4ad5a985b472", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8da6dc1d401da0aa99b55921744611d090ea753bf65d04dbc229ae0fbaf1df5b741acbcba381806b7b9b7947ed937ad2db573afdde454dca467cfe0ce5d8851", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3d7041e78daca4e3a9be8628ef1ee62a739ceefde739cccf5f278a2d8a4c5d1130376547e74e7b41384172a065016856388fe3082f9b7e728404d0950f6e59b2"}' ========= master_lag: 0.54 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 17:44:06.068615) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 17:44:06.068951) ========= 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.TpZ7NdcAyF/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.I40ifJHPbc/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005115 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 17:44:06.120705) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 17:44:06.121027) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 17:44:06.121361) ========= ========= Total master_lag: 1.19 =========