builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0046 starttime: 1462092948.63 results: success (0) buildid: 20160501004106 builduid: e2f3cd821e7544668a80ddbdae6e1bcf revision: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:48.633087) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:48.633471) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:48.633741) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:48.748337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:48.748595) ========= 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.5fY73WUSNq/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.408s8mflgi/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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-01 01:55:48-- 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% 14.2M=0.001s 2016-05-01 01:55:49 (14.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.493746 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:49.321546) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:49.321823) ========= 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.5fY73WUSNq/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.408s8mflgi/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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.020334 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:49.423000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-01 01:55:49.423503) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3f169c95e542f059b6b07d11e7f73533bdbf03d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3f169c95e542f059b6b07d11e7f73533bdbf03d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5fY73WUSNq/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.408s8mflgi/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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-01 01:55:49,512 truncating revision to first 12 chars 2016-05-01 01:55:49,512 Setting DEBUG logging. 2016-05-01 01:55:49,512 attempt 1/10 2016-05-01 01:55:49,512 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3f169c95e542?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-01 01:55:51,159 unpacking tar archive at: mozilla-aurora-3f169c95e542/testing/mozharness/ program finished with exit code 0 elapsedTime=1.896355 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-01 01:55:51.427725) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:51.428073) ========= script_repo_revision: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:51.428468) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:51.428735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-01 01:55:52.047220) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 35 secs) (at 2016-05-01 01:55:52.047594) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5fY73WUSNq/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.408s8mflgi/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:55:52 INFO - MultiFileLogger online at 20160501 01:55:52 in /builds/slave/test 01:55:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:55:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:55:52 INFO - {'append_to_log': False, 01:55:52 INFO - 'base_work_dir': '/builds/slave/test', 01:55:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:55:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:55:52 INFO - 'branch': 'Mozilla-Aurora', 01:55:52 INFO - 'buildbot_json_path': 'buildprops.json', 01:55:52 INFO - 'config_files': ('talos/mac_config.py',), 01:55:52 INFO - 'default_actions': ('clobber', 01:55:52 INFO - 'read-buildbot-config', 01:55:52 INFO - 'download-and-extract', 01:55:52 INFO - 'populate-webroot', 01:55:52 INFO - 'create-virtualenv', 01:55:52 INFO - 'install', 01:55:52 INFO - 'run-tests'), 01:55:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:55:52 INFO - 'download_minidump_stackwalk': True, 01:55:52 INFO - 'download_symbols': 'ondemand', 01:55:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:55:52 INFO - 'tooltool.py': '/tools/tooltool.py', 01:55:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:55:52 INFO - '/tools/misc-python/virtualenv.py')}, 01:55:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:55:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:55:52 INFO - 'installer_path': 'installer.exe', 01:55:52 INFO - 'log_level': 'info', 01:55:52 INFO - 'log_name': 'talos', 01:55:52 INFO - 'log_to_console': True, 01:55:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:55:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:55:52 INFO - 'opt_config_files': (), 01:55:52 INFO - 'pip_index': False, 01:55:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:55:52 INFO - 'cmd': ('bash', 01:55:52 INFO - '-c', 01:55:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:55:52 INFO - 'enabled': True, 01:55:52 INFO - 'halt_on_failure': False, 01:55:52 INFO - 'name': 'check_screen_resolution'},), 01:55:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:55:52 INFO - 'cmd': ('bash', 01:55:52 INFO - '-c', 01:55:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:55:52 INFO - 'enabled': True, 01:55:52 INFO - 'halt_on_failure': False, 01:55:52 INFO - 'name': 'check_screen_resolution'},), 01:55:52 INFO - 'run_cmd_checks_enabled': True, 01:55:52 INFO - 'sps_profile': False, 01:55:52 INFO - 'sps_profile_interval': 0, 01:55:52 INFO - 'suite': 'dromaeojs', 01:55:52 INFO - 'system_bits': '32', 01:55:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:55:52 INFO - 'title': 't-yosemite-r7-0046', 01:55:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:55:52 INFO - 'use_talos_json': True, 01:55:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:55:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:55:52 INFO - 'work_dir': 'build'} 01:55:52 INFO - ##### 01:55:52 INFO - ##### Running clobber step. 01:55:52 INFO - ##### 01:55:52 INFO - Running pre-action listener: _resource_record_pre_action 01:55:52 INFO - Running main action method: clobber 01:55:52 INFO - rmtree: /builds/slave/test/build 01:55:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:55:53 INFO - Running post-action listener: _resource_record_post_action 01:55:53 INFO - ##### 01:55:53 INFO - ##### Running read-buildbot-config step. 01:55:53 INFO - ##### 01:55:53 INFO - Running pre-action listener: _resource_record_pre_action 01:55:53 INFO - Running main action method: read_buildbot_config 01:55:53 INFO - Using buildbot properties: 01:55:53 INFO - { 01:55:53 INFO - "project": "", 01:55:53 INFO - "product": "firefox", 01:55:53 INFO - "script_repo_revision": "production", 01:55:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:55:53 INFO - "repository": "", 01:55:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:55:53 INFO - "buildid": "20160501004106", 01:55:53 INFO - "stage_platform": "macosx64", 01:55:53 INFO - "basedir": "/builds/slave/test", 01:55:53 INFO - "nightly_build": "True", 01:55:53 INFO - "buildnumber": 302, 01:55:53 INFO - "slavename": "t-yosemite-r7-0046", 01:55:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 01:55:53 INFO - "platform": "yosemite_r7", 01:55:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:55:53 INFO - "branch": "mozilla-aurora", 01:55:53 INFO - "repo_path": "releases/mozilla-aurora", 01:55:53 INFO - "revision": "3f169c95e542f059b6b07d11e7f73533bdbf03d6", 01:55:53 INFO - "pgo_build": "False", 01:55:53 INFO - "builduid": "e2f3cd821e7544668a80ddbdae6e1bcf", 01:55:53 INFO - "slavebuilddir": "test" 01:55:53 INFO - } 01:55:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 01:55:53 INFO - Running post-action listener: _resource_record_post_action 01:55:53 INFO - ##### 01:55:53 INFO - ##### Running download-and-extract step. 01:55:53 INFO - ##### 01:55:53 INFO - Running pre-action listener: _resource_record_pre_action 01:55:53 INFO - Running main action method: download_and_extract 01:55:53 INFO - mkdir: /builds/slave/test/build/tests 01:55:53 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')]} 01:55:53 INFO - https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:55:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 01:55:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 01:55:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 01:55:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 01:56:23 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 01:56:23 INFO - retry: attempt #1 caught exception: timed out 01:56:23 INFO - retry: Failed, sleeping 30 seconds before retrying 01:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 01:57:23 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 01:57:23 INFO - retry: attempt #2 caught exception: timed out 01:57:23 INFO - retry: Failed, sleeping 60 seconds before retrying 01:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #3 01:58:24 INFO - Downloaded 1199 bytes. 01:58:24 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 01:58:24 INFO - Using the following test package requirements: 01:58:24 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 01:58:24 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 01:58:24 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 01:58:24 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'jsshell-mac.zip'], 01:58:24 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 01:58:24 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 01:58:24 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 01:58:24 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 01:58:24 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 01:58:24 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 01:58:24 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 01:58:24 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:58: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')]} 01:58:24 INFO - https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:58:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 01:58:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 01:58:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 01:58:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:58:25 INFO - Downloaded 18110512 bytes. 01:58:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:58:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:58:28 INFO - Return code: 0 01:58:28 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:28 INFO - https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:58:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 01:58:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 01:58:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 01:58:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:58:32 INFO - Downloaded 18110512 bytes. 01:58:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:58:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:58:35 INFO - Return code: 0 01:58:35 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')]} 01:58:35 INFO - https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 01:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 01:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 01:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:58:37 INFO - Downloaded 10840145 bytes. 01:58:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:58:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:58:37 INFO - Return code: 0 01:58:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:37 INFO - https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 01:58:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 01:58:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 01:58:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 01:58:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 01:58:41 INFO - Downloaded 114209029 bytes. 01:58:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 01:58:41 INFO - mkdir: /builds/slave/test/properties 01:58:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:58:41 INFO - Writing to file /builds/slave/test/properties/build_url 01:58:41 INFO - Contents: 01:58:41 INFO - build_url:https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 01:58:42 INFO - Running post-action listener: _resource_record_post_action 01:58:42 INFO - Running post-action listener: set_extra_try_arguments 01:58:42 INFO - ##### 01:58:42 INFO - ##### Running populate-webroot step. 01:58:42 INFO - ##### 01:58:42 INFO - Running pre-action listener: _resource_record_pre_action 01:58:42 INFO - Running main action method: populate_webroot 01:58:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:58:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:58:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:58:42 INFO - '--tppagecycles', 01:58:42 INFO - '7'], 01:58:42 INFO - 'tests': ['tsvgm']}}, 01:58:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:58:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:58:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:58:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:58:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:58:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:58:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['damp', 'tps']}, 01:58:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'tests': ['damp', 'tps']}, 01:58:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['dromaeo_dom']}, 01:58:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:58:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['a11yr', 01:58:42 INFO - 'ts_paint', 01:58:42 INFO - 'tpaint', 01:58:42 INFO - 'sessionrestore', 01:58:42 INFO - 'sessionrestore_no_auto_restore', 01:58:42 INFO - 'tabpaint']}, 01:58:42 INFO - 'other-e10s': {'tests': ['a11yr', 01:58:42 INFO - 'ts_paint', 01:58:42 INFO - 'tpaint', 01:58:42 INFO - 'sessionrestore', 01:58:42 INFO - 'sessionrestore_no_auto_restore', 01:58:42 INFO - 'tabpaint']}, 01:58:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:58:42 INFO - 'ts_paint', 01:58:42 INFO - 'tpaint', 01:58:42 INFO - 'sessionrestore', 01:58:42 INFO - 'sessionrestore_no_auto_restore', 01:58:42 INFO - 'tabpaint']}, 01:58:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:58:42 INFO - 'ts_paint', 01:58:42 INFO - 'tpaint', 01:58:42 INFO - 'sessionrestore', 01:58:42 INFO - 'sessionrestore_no_auto_restore', 01:58:42 INFO - 'tabpaint']}, 01:58:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['a11yr', 01:58:42 INFO - 'ts_paint', 01:58:42 INFO - 'tpaint', 01:58:42 INFO - 'sessionrestore', 01:58:42 INFO - 'sessionrestore_no_auto_restore', 01:58:42 INFO - 'tabpaint']}, 01:58:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['a11yr', 01:58:42 INFO - 'ts_paint', 01:58:42 INFO - 'tpaint', 01:58:42 INFO - 'sessionrestore', 01:58:42 INFO - 'sessionrestore_no_auto_restore', 01:58:42 INFO - 'tabpaint']}, 01:58:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['tsvgx', 01:58:42 INFO - 'tsvgr_opacity', 01:58:42 INFO - 'tart', 01:58:42 INFO - 'tscrollx', 01:58:42 INFO - 'cart']}, 01:58:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:58:42 INFO - 'tsvgr_opacity', 01:58:42 INFO - 'tart', 01:58:42 INFO - 'tscrollx', 01:58:42 INFO - 'cart']}, 01:58:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'talos_options': ['--disable-e10s'], 01:58:42 INFO - 'tests': ['tp5o']}, 01:58:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'tests': ['tp5o']}, 01:58:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'talos_options': ['--disable-e10s', 01:58:42 INFO - '--xperf_path', 01:58:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:58:42 INFO - 'tests': ['tp5n']}, 01:58:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:42 INFO - 'talos_options': ['--xperf_path', 01:58:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:58:42 INFO - 'tests': ['tp5n']}}, 01:58:42 INFO - 'talos.zip': {'path': '', 01:58:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:58:42 INFO - Running post-action listener: _resource_record_post_action 01:58:42 INFO - ##### 01:58:42 INFO - ##### Running create-virtualenv step. 01:58:42 INFO - ##### 01:58:42 INFO - Running pre-action listener: _resource_record_pre_action 01:58:42 INFO - Running main action method: create_virtualenv 01:58:42 INFO - Creating virtualenv /builds/slave/test/build/venv 01:58:42 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 01:58:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:58:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:58:42 INFO - Using real prefix '/tools/python27' 01:58:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:58:43 INFO - Installing distribute.............................................................................................................................................................................................done. 01:58:46 INFO - Installing pip.................done. 01:58:46 INFO - Return code: 0 01:58:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:58:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:58:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:58:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:46 INFO - 'HOME': '/Users/cltbld', 01:58:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:46 INFO - 'LOGNAME': 'cltbld', 01:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:46 INFO - 'MOZ_NO_REMOTE': '1', 01:58:46 INFO - 'NO_EM_RESTART': '1', 01:58:46 INFO - 'PAGER': '/bin/cat', 01:58:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:46 INFO - 'PWD': '/builds/slave/test', 01:58:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:46 INFO - 'SHELL': '/bin/bash', 01:58:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:58:46 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:58:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:46 INFO - 'USER': 'cltbld', 01:58:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:46 INFO - 'XPC_FLAGS': '0x0', 01:58:46 INFO - 'XPC_SERVICE_NAME': '0', 01:58:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:46 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 01:58:46 INFO - Cleaning up... 01:58:46 INFO - Return code: 0 01:58:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:58:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 01:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:58:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:58:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:46 INFO - 'HOME': '/Users/cltbld', 01:58:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:46 INFO - 'LOGNAME': 'cltbld', 01:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:46 INFO - 'MOZ_NO_REMOTE': '1', 01:58:46 INFO - 'NO_EM_RESTART': '1', 01:58:46 INFO - 'PAGER': '/bin/cat', 01:58:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:46 INFO - 'PWD': '/builds/slave/test', 01:58:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:46 INFO - 'SHELL': '/bin/bash', 01:58:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:58:46 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:58:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:46 INFO - 'USER': 'cltbld', 01:58:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:46 INFO - 'XPC_FLAGS': '0x0', 01:58:46 INFO - 'XPC_SERVICE_NAME': '0', 01:58:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:46 INFO - Downloading/unpacking psutil>=0.7.1 01:58: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 01:58: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 01:58: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 01:58: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 01:58: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 01:58: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 01:58:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:58:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:58:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:58:49 INFO - Installing collected packages: psutil 01:58:49 INFO - Running setup.py install for psutil 01:58:49 INFO - building 'psutil._psutil_osx' extension 01:58:49 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 01:58:49 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 01:58:49 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 01:58:49 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 01:58:49 INFO - building 'psutil._psutil_posix' extension 01:58:49 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 01:58:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:58:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:58:49 INFO - ^ 01:58:49 INFO - 1 warning generated. 01:58:49 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 01:58:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:58:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:58:49 INFO - Successfully installed psutil 01:58:49 INFO - Cleaning up... 01:58:50 INFO - Return code: 0 01:58:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:50 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:58:50 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.0.0'] in /builds/slave/test/build 01:58:50 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.0.0 01:58:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:58:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:50 INFO - 'HOME': '/Users/cltbld', 01:58:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:50 INFO - 'LOGNAME': 'cltbld', 01:58:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:50 INFO - 'MOZ_NO_REMOTE': '1', 01:58:50 INFO - 'NO_EM_RESTART': '1', 01:58:50 INFO - 'PAGER': '/bin/cat', 01:58:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:50 INFO - 'PWD': '/builds/slave/test', 01:58:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:50 INFO - 'SHELL': '/bin/bash', 01:58:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:58:50 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:58:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:50 INFO - 'USER': 'cltbld', 01:58:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:50 INFO - 'XPC_FLAGS': '0x0', 01:58:50 INFO - 'XPC_SERVICE_NAME': '0', 01:58:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:58:50 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 01:58:50 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 01:58:50 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 01:58:50 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 01:58:50 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 01:58:50 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 01:58:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:58:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:58:52 INFO - Installing collected packages: mozsystemmonitor 01:58:52 INFO - Running setup.py install for mozsystemmonitor 01:58:52 INFO - Successfully installed mozsystemmonitor 01:58:52 INFO - Cleaning up... 01:58:53 INFO - Return code: 0 01:58:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:58:53 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')]} 01:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:53 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')]} 01:58:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:53 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 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:58:53 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 01:58:53 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 01:58:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:58:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:53 INFO - 'HOME': '/Users/cltbld', 01:58:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:53 INFO - 'LOGNAME': 'cltbld', 01:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:53 INFO - 'MOZ_NO_REMOTE': '1', 01:58:53 INFO - 'NO_EM_RESTART': '1', 01:58:53 INFO - 'PAGER': '/bin/cat', 01:58:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:53 INFO - 'PWD': '/builds/slave/test', 01:58:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:53 INFO - 'SHELL': '/bin/bash', 01:58:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:58:53 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:58:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:53 INFO - 'USER': 'cltbld', 01:58:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:53 INFO - 'XPC_FLAGS': '0x0', 01:58:53 INFO - 'XPC_SERVICE_NAME': '0', 01:58:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:53 INFO - Downloading/unpacking blobuploader==1.2.4 01:58:53 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 01:58:53 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 01:58:53 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 01:58:53 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 01:58:53 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 01:58:53 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 01:58:55 INFO - Downloading blobuploader-1.2.4.tar.gz 01:58:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:58:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:58:55 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 01:58:55 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 01:58:55 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 01:58:55 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 01:58:55 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 01:58:55 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 01:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:58:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:58:56 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 01:58:56 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 01:58:56 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 01:58:56 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 01:58:56 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 01:58:56 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 01:58:56 INFO - Downloading docopt-0.6.1.tar.gz 01:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:58:56 INFO - Installing collected packages: blobuploader, requests, docopt 01:58:56 INFO - Running setup.py install for blobuploader 01:58:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:58:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:58:57 INFO - Running setup.py install for requests 01:58:57 INFO - Running setup.py install for docopt 01:58:57 INFO - Successfully installed blobuploader requests docopt 01:58:57 INFO - Cleaning up... 01:58:57 INFO - Return code: 0 01:58:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:58: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')]} 01:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58: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')]} 01:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:57 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 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:58:57 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 01:58:57 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 01:58:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:58:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:57 INFO - 'HOME': '/Users/cltbld', 01:58:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:57 INFO - 'LOGNAME': 'cltbld', 01:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:57 INFO - 'MOZ_NO_REMOTE': '1', 01:58:57 INFO - 'NO_EM_RESTART': '1', 01:58:57 INFO - 'PAGER': '/bin/cat', 01:58:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:57 INFO - 'PWD': '/builds/slave/test', 01:58:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:57 INFO - 'SHELL': '/bin/bash', 01:58:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:58:57 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:58:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:57 INFO - 'USER': 'cltbld', 01:58:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:57 INFO - 'XPC_FLAGS': '0x0', 01:58:57 INFO - 'XPC_SERVICE_NAME': '0', 01:58:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-RmPkAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-aAG__Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-JGqQhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-cq7FYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-8DkXts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-T8MYVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-gmuXXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-aZbRl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-agS7Wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:58:58 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-VQyFHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-dUAByq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-WnCB7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-vkqjH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-FapSjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-UOHXVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-GeF47m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:58:59 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-nhbbZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:58:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:58:59 INFO - Running setup.py install for manifestparser 01:58:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:58:59 INFO - Running setup.py install for mozcrash 01:59:00 INFO - Running setup.py install for mozdebug 01:59:00 INFO - Running setup.py install for mozdevice 01:59:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Running setup.py install for mozfile 01:59:00 INFO - Running setup.py install for mozhttpd 01:59:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Running setup.py install for mozinfo 01:59:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Running setup.py install for mozInstall 01:59:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:59:00 INFO - Running setup.py install for mozleak 01:59:01 INFO - Running setup.py install for mozlog 01:59:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:59:01 INFO - Running setup.py install for moznetwork 01:59:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:59:01 INFO - Running setup.py install for mozprocess 01:59:01 INFO - Running setup.py install for mozprofile 01:59:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:59:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:59:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:59:01 INFO - Running setup.py install for mozrunner 01:59:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:59:01 INFO - Running setup.py install for mozscreenshot 01:59:02 INFO - Running setup.py install for moztest 01:59:02 INFO - Running setup.py install for mozversion 01:59:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:59:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:59:02 INFO - Cleaning up... 01:59:02 INFO - Return code: 0 01:59:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:02 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 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:59:02 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 01:59:02 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 01:59:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:59:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:02 INFO - 'HOME': '/Users/cltbld', 01:59:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:02 INFO - 'LOGNAME': 'cltbld', 01:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:02 INFO - 'MOZ_NO_REMOTE': '1', 01:59:02 INFO - 'NO_EM_RESTART': '1', 01:59:02 INFO - 'PAGER': '/bin/cat', 01:59:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:02 INFO - 'PWD': '/builds/slave/test', 01:59:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:02 INFO - 'SHELL': '/bin/bash', 01:59:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:59:02 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:59:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:02 INFO - 'USER': 'cltbld', 01:59:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:02 INFO - 'XPC_FLAGS': '0x0', 01:59:02 INFO - 'XPC_SERVICE_NAME': '0', 01:59:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:59:02 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-MQpnfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:59:02 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)) 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:59:02 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Dxp19E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:59:02 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)) 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:59:02 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-ZLfhT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:59:02 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)) 01:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:59:02 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-0jKhLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-5EurYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-6oTSSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-1ym4VR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-kF41tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-N0iHW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-tetiHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:59:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-wH2mDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-uVseCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-uVpIp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:59:03 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)) 01:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:59:03 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Ra3e_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:59:04 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)) 01:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:59:04 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-kNIuZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:59:04 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)) 01:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:59:04 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-AqaQ7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:59:04 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)) 01:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:59:04 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-u4wSFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:59:04 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)) 01:59:04 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)) 01:59:04 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)) 01:59:04 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)) 01:59:04 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)) 01:59:04 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)) 01:59:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:06 INFO - Downloading blessings-1.6.tar.gz 01:59:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:59:06 INFO - Installing collected packages: blessings 01:59:06 INFO - Running setup.py install for blessings 01:59:07 INFO - Successfully installed blessings 01:59:07 INFO - Cleaning up... 01:59:07 INFO - Return code: 0 01:59:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:59:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:59:07 INFO - Reading from file tmpfile_stdout 01:59:07 INFO - Current package versions: 01:59:07 INFO - blessings == 1.6 01:59:07 INFO - blobuploader == 1.2.4 01:59:07 INFO - docopt == 0.6.1 01:59:07 INFO - manifestparser == 1.1 01:59:07 INFO - mozInstall == 1.12 01:59:07 INFO - mozcrash == 0.17 01:59:07 INFO - mozdebug == 0.1 01:59:07 INFO - mozdevice == 0.48 01:59:07 INFO - mozfile == 1.2 01:59:07 INFO - mozhttpd == 0.7 01:59:07 INFO - mozinfo == 0.9 01:59:07 INFO - mozleak == 0.1 01:59:07 INFO - mozlog == 3.1 01:59:07 INFO - moznetwork == 0.27 01:59:07 INFO - mozprocess == 0.22 01:59:07 INFO - mozprofile == 0.28 01:59:07 INFO - mozrunner == 6.11 01:59:07 INFO - mozscreenshot == 0.1 01:59:07 INFO - mozsystemmonitor == 0.0 01:59:07 INFO - moztest == 0.7 01:59:07 INFO - mozversion == 1.4 01:59:07 INFO - psutil == 3.1.1 01:59:07 INFO - requests == 1.2.3 01:59:07 INFO - wsgiref == 0.1.2 01:59:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:59:07 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')]} 01:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:07 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')]} 01:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:07 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 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:59:07 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 01:59:07 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 01:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:07 INFO - 'HOME': '/Users/cltbld', 01:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:07 INFO - 'LOGNAME': 'cltbld', 01:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:07 INFO - 'MOZ_NO_REMOTE': '1', 01:59:07 INFO - 'NO_EM_RESTART': '1', 01:59:07 INFO - 'PAGER': '/bin/cat', 01:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:07 INFO - 'PWD': '/builds/slave/test', 01:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:07 INFO - 'SHELL': '/bin/bash', 01:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:59:07 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:07 INFO - 'USER': 'cltbld', 01:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:07 INFO - 'XPC_FLAGS': '0x0', 01:59:07 INFO - 'XPC_SERVICE_NAME': '0', 01:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 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)) 01:59:07 INFO - Cleaning up... 01:59:07 INFO - Return code: 0 01:59:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:59:07 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')]} 01:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:07 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')]} 01:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:07 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 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:59:07 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 01:59:07 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 01:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:07 INFO - 'HOME': '/Users/cltbld', 01:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:07 INFO - 'LOGNAME': 'cltbld', 01:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:07 INFO - 'MOZ_NO_REMOTE': '1', 01:59:07 INFO - 'NO_EM_RESTART': '1', 01:59:07 INFO - 'PAGER': '/bin/cat', 01:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:07 INFO - 'PWD': '/builds/slave/test', 01:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:07 INFO - 'SHELL': '/bin/bash', 01:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:59:07 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:07 INFO - 'USER': 'cltbld', 01:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:07 INFO - 'XPC_FLAGS': '0x0', 01:59:07 INFO - 'XPC_SERVICE_NAME': '0', 01:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:07 INFO - Downloading/unpacking jsonschema 01:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:59:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:59:10 INFO - Downloading/unpacking functools32 (from jsonschema) 01:59:10 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 01:59:10 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 01:59:10 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 01:59:10 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 01:59:10 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 01:59:10 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 01:59:10 INFO - Downloading functools32-3.2.3-2.tar.gz 01:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:59:10 INFO - warning: no files found matching '*.txt' 01:59:10 INFO - no previously-included directories found matching 'build' 01:59:10 INFO - no previously-included directories found matching 'dist' 01:59:10 INFO - no previously-included directories found matching '.git*' 01:59:10 INFO - Installing collected packages: jsonschema, functools32 01:59:10 INFO - Running setup.py install for functools32 01:59:11 INFO - warning: no files found matching '*.txt' 01:59:11 INFO - no previously-included directories found matching 'build' 01:59:11 INFO - no previously-included directories found matching 'dist' 01:59:11 INFO - no previously-included directories found matching '.git*' 01:59:11 INFO - Successfully installed jsonschema functools32 01:59:11 INFO - Cleaning up... 01:59:11 INFO - Return code: 0 01:59:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:11 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 0x102524a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102534420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae40c9e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, '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.5fY73WUSNq/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.408s8mflgi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 01:59:11 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 01:59:11 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 01:59:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:59:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:11 INFO - 'HOME': '/Users/cltbld', 01:59:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:11 INFO - 'LOGNAME': 'cltbld', 01:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:11 INFO - 'MOZ_NO_REMOTE': '1', 01:59:11 INFO - 'NO_EM_RESTART': '1', 01:59:11 INFO - 'PAGER': '/bin/cat', 01:59:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:11 INFO - 'PWD': '/builds/slave/test', 01:59:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:11 INFO - 'SHELL': '/bin/bash', 01:59:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:59:11 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:59:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:11 INFO - 'USER': 'cltbld', 01:59:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:11 INFO - 'XPC_FLAGS': '0x0', 01:59:11 INFO - 'XPC_SERVICE_NAME': '0', 01:59:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:11 INFO - Downloading/unpacking flake8 01:59:11 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 01:59:11 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 01:59:11 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 01:59:11 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 01:59:11 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 01:59:11 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 01:59:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:59:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:59:13 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 01:59:13 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 01:59:13 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 01:59:13 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 01:59:13 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 01:59:13 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 01:59:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:59:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 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 01:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:59:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:59:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:59:15 INFO - no previously-included directories found matching 'docs/_build' 01:59:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:59:15 INFO - Running setup.py install for pep8 01:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:59:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:59:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:59:15 INFO - no previously-included directories found matching 'docs/_build' 01:59:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:59:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:59:15 INFO - Cleaning up... 01:59:15 INFO - Return code: 0 01:59:15 INFO - Running post-action listener: _resource_record_post_action 01:59:15 INFO - Running post-action listener: _start_resource_monitoring 01:59:15 INFO - Starting resource monitoring. 01:59:15 INFO - ##### 01:59:15 INFO - ##### Running install step. 01:59:15 INFO - ##### 01:59:15 INFO - Running pre-action listener: _resource_record_pre_action 01:59:15 INFO - Running main action method: install 01:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:59:15 INFO - Reading from file tmpfile_stdout 01:59:15 INFO - Detecting whether we're running mozinstall >=1.0... 01:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:59:15 INFO - Reading from file tmpfile_stdout 01:59:15 INFO - Output received: 01:59:15 INFO - Usage: mozinstall [options] installer 01:59:15 INFO - Options: 01:59:15 INFO - -h, --help show this help message and exit 01:59:15 INFO - -d DEST, --destination=DEST 01:59:15 INFO - Directory to install application into. [default: 01:59:15 INFO - "/builds/slave/test"] 01:59:15 INFO - --app=APP Application being installed. [default: firefox] 01:59:15 INFO - mkdir: /builds/slave/test/build/application 01:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:59:47 INFO - Reading from file tmpfile_stdout 01:59:47 INFO - Output received: 01:59:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:59:47 INFO - Running post-action listener: _resource_record_post_action 01:59:47 INFO - ##### 01:59:47 INFO - ##### Running run-tests step. 01:59:47 INFO - ##### 01:59:47 INFO - Running pre-action listener: _resource_record_pre_action 01:59:47 INFO - Running main action method: run_tests 01:59:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:59:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:59:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:59:47 INFO - 2016-05-01 01:59:47.525 screenresolution[1931:24776] starting screenresolution argv=screenresolution get 01:59:47 INFO - 2016-05-01 01:59:47.528 screenresolution[1931:24776] Display 0: 1600x1200x32@60 01:59:47 INFO - 2016-05-01 01:59:47.534 screenresolution[1932:24782] starting screenresolution argv=screenresolution list 01:59:47 INFO - Available Modes on Display 0 01:59:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:59:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:59:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:59:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:59:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:59:47 INFO - Intel Iris: 01:59:47 INFO - Chipset Model: Intel Iris 01:59:47 INFO - Type: GPU 01:59:47 INFO - Bus: Built-In 01:59:47 INFO - VRAM (Dynamic, Max): 1536 MB 01:59:47 INFO - Vendor: Intel (0x8086) 01:59:47 INFO - Device ID: 0x0a2e 01:59:47 INFO - Revision ID: 0x0009 01:59:47 INFO - Displays: 01:59:47 INFO - Display: 01:59:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:59:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:59:47 INFO - Display Serial Number: 1600x1200 60 01:59:47 INFO - Main Display: Yes 01:59:47 INFO - Mirror: Off 01:59:47 INFO - Online: Yes 01:59:47 INFO - Rotation: Supported 01:59:47 INFO - Return code: 0 01:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:59:47 INFO - Python 2.7.3 01:59:47 INFO - Return code: 0 01:59:47 INFO - grabbing minidump binary from tooltool 01:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:47 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1023c3e00>, '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 0x10222cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1023beb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:59:47 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 01:59:47 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 01:59:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:59:47 INFO - Return code: 0 01:59:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:59:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:59:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:59:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:59:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:59:48 INFO - Return code: 0 01:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--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 01:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0046 --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 01:59:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5fY73WUSNq/Render', 01:59:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:48 INFO - 'HOME': '/Users/cltbld', 01:59:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:48 INFO - 'LOGNAME': 'cltbld', 01:59:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:59:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:48 INFO - 'MOZ_NO_REMOTE': '1', 01:59:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:59:48 INFO - 'NO_EM_RESTART': '1', 01:59:48 INFO - 'PAGER': '/bin/cat', 01:59:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:48 INFO - 'PWD': '/builds/slave/test', 01:59:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:59:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:48 INFO - 'SHELL': '/bin/bash', 01:59:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.408s8mflgi/Listeners', 01:59:48 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 01:59:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:48 INFO - 'USER': 'cltbld', 01:59:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:48 INFO - 'XPC_FLAGS': '0x0', 01:59:48 INFO - 'XPC_SERVICE_NAME': '0', 01:59:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--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 01:59:49 INFO - mozversion application_buildid: 20160501004106 01:59:49 INFO - mozversion application_changeset: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 01:59:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:59:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:59:49 INFO - mozversion application_name: Firefox 01:59:49 INFO - mozversion application_remotingname: firefox-dev 01:59:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:59:49 INFO - mozversion application_vendor: Mozilla 01:59:49 INFO - mozversion application_version: 48.0a2 01:59:49 INFO - mozversion platform_buildid: 20160501004106 01:59:49 INFO - mozversion platform_changeset: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 01:59:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:59:49 INFO - mozversion platform_version: 48.0a2 01:59:49 INFO - using testdate: 1462093189 01:59:49 INFO - actual date: 1462093189 01:59:49 INFO - starting webserver on 'localhost:54202' 01:59:49 INFO - SUITE-START | Running 2 tests 01:59:49 INFO - TEST-START | dromaeo_css 01:59:49 INFO - operating with platform_type : mac_ 01:59:49 INFO - Initialising browser for dromaeo_css test... 01:59:49 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpWrpD5m/profile http://localhost:54202/getInfo.html) 01:59:51 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 01:59:51 INFO - PROCESS | 1950 | colorDepth:24 01:59:51 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 01:59:51 INFO - PROCESS | 1950 | __metrics 01:59:51 INFO - PROCESS | 1950 | 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" 01:59:51 ERROR - PROCESS | 1950 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:59:52 INFO - TEST-INFO | 1950: exit 0 01:59:52 INFO - Browser initialized. 01:59:52 INFO - Running cycle 1/1 for dromaeo_css test... 01:59:52 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpWrpD5m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:00:04 INFO - PROCESS | 1953 | RSS: Main: 211292160 02:00:04 INFO - PROCESS | 1953 | 02:00:55 INFO - PROCESS | 1953 | 1462093255678 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] 02:03:05 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:54202/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54202/tests/dromaeo/cssquery-ext.html) 02:03:07 INFO - PROCESS | 1953 | RSS: Main: 300900352 02:03:07 INFO - PROCESS | 1953 | 02:06:09 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:54202/tests/dromaeo/cssquery-ext.html (next: http://localhost:54202/tests/dromaeo/cssquery-jquery.html) 02:06:10 INFO - PROCESS | 1953 | RSS: Main: 351055872 02:06:10 INFO - PROCESS | 1953 | 02:09:12 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:54202/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54202/tests/dromaeo/cssquery-mootools.html) 02:09:13 INFO - PROCESS | 1953 | RSS: Main: 361160704 02:09:13 INFO - PROCESS | 1953 | 02:12:15 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:54202/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54202/tests/dromaeo/cssquery-prototype.html) 02:12:15 INFO - PROCESS | 1953 | RSS: Main: 359354368 02:12:15 INFO - PROCESS | 1953 | 02:15:19 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:54202/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54202/tests/dromaeo/cssquery-yui.html) 02:15:19 INFO - PROCESS | 1953 | RSS: Main: 331866112 02:15:19 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:54202/tests/dromaeo/cssquery-yui.html (next: http://localhost:54202/tests/dromaeo/cssquery-dojo.html) 02:18:22 INFO - PROCESS | 1953 | RSS: Main: 366694400 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | __start_tp_report 02:18:22 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 02:18:22 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 02:18:22 INFO - PROCESS | 1953 | |i|pagename|runs| 02:18:22 INFO - PROCESS | 1953 | |0;dojo.html;3122.5628412022315;3424.6061702904167;3425.8458369373375;3431.210821511054;3469.6183419823815;5660.575456840738;5669.886602267952;5670.121131224667;5677.829665110043;5687.829365119042;5663.9433605664035;5694.345150307716;5695.45893140152;5696.715164241792;5699.772009119641;5078.958813443235;5119.001794650045;5143.177091665334;5162.277281180638;5184.637075404723;5204.739763011853;5211.86970325743;5217.660852044605;5219.530242278194;5226.529612334889;5111.054454925832;5156.639035267533;5179.378474583055;5192.273081768537;5202.869928251786;5190.948090519103;5207.270982062514;5209.0363282792805;5235.345581802275;5245.86885327866;4316.525182229952;5013.974930125362;5041.546260836542;5121.539061484485;5142.562882155022;5521.365043020024;5529.502344788948;5548.528375088123;5588.804391846306;5654.717264136777;6180.505559555225;6208;6210.441060304594;6222.844428889314;6226.221722284715;6146.938530614681;6164.6917654117115;6232.875342493125;6266.686665666698;6286.025666021774;5781.6241944274325;5795.88408231833;5796.101604251347;5797.681127537984;5838.649681019195;6307.400796924285;6312.021636646327;6313.40022697843;6313.684315784192;6319.905201421982;5158.613104017214;6019.759209631653;6104.481119104837;6163.19878415812;6168.691565421711;6057.909131363033;6063.514918806485;6064.120702498113;6080.817575472743;6141.96929015361;5969.701514924323;5981.700914954235;5984.222051133324;6011.188489553854;6020.458158765732;5997.370276121032;6007.849803754941;6153.876922461525;6154.476869466056;6154.907676384858;4901.730404827768;4962.429320628174;4972.8508144755715;4989.301497790312;5007.123753343223;4345.369921361448;4395.27477966132;4402.779861006937;4409.140217657526;4444.511103778582;5525.977694126599;5638.154276858504;5667.914981275284;5679.4320567943705;5696.11710184922;6175.382461753788;6278.372162783777;6298.433141017239;6303.684815759194;6313.873722525524;5643.101465294475;5660.122680984454;5665.093585026376;5669.943300566942;5671.092625179951;5245.370555533361;5332.893342133136;5340.8664783379945;5390.811321603725;5392.191171324333;6335.524835637346;6409.7436102555375;6411.487081033506;6417.358264173732;6430.2605200400685;6263.185785847811;6264;6309.652969086745;6313.116163737172;6319.431251187415;6236.251649802053;6255.687215639291;6390.073439351175;6406.359364063556;6411.743530258737;5979.970100149471;6016.127661489147;6025.758969641165;6068.150458935665;6068.332483426731;5286.471352864683;6164.938350616437;6173.166622505904;6176.9073463897175;6181.721822518088;5882.558808089496;5894.2926848778425;5939.821805345847;6042.577019608585;6140.815775526741;6124.418180272867;6127.6936153193055;6134.263888333428;6141.232345956755;6148.538859585638;5912.615679980788;5971.910421343597;5978.581499295182;5979.910301345571;5987.101934709828;5899.557533185114;5916.911246331395;6101.420365065312;6104.786332478342;6120.693965301806;5908.881822363614;5938.198343223609;5941.970290148522;5962.1951036611235;5964.7912323068485 02:18:22 INFO - PROCESS | 1953 | |1;ext.html;499.0991260774303;507.43674521281383;514.3801718928692;521.1349160393747;560.924275222845;29180.270493237655;36399;40600.79699601498;40768.59231407685;40790.38814417782;42584;43142.784286078524;43239.27042918287;43249.56750432495;43496.91257718558;8613.526256055915;8814.118588141198;9660.903390966107;9874.456904870229;9875.95062024689;11503;11698.532058717683;11729.589464368748;11730.7653846923;11778.941105294462;11749.471273792657;11940.641780746548;11954.282743035446;11955.222910510789;11957.521699132069;8643.481391116522;11599.826002609883;11695.415229238504;11836.46735896878;11848.992835608897;11637.35994520301;11800.114991375682;11804.291742495478;11806.822897656542;11833.881661183364;41328.173436531404;42029.57970420287;42080.579194207974;42089.579104208875;42171.578284217074;2883.9711602883913;3195.6005499312587;3238.2875767331147;3289.3750187464307;3392.694657480814;27674;28057;28401.71598284032;29217.853910730522;30585.847070764725;2706.2287248134385;2706.6075419064323;2737.931551711223;2795.9720402795915;2801.80387372884;322797;390669;391592.04203979083;392383;393536;62950.68524657439;64207.67896160489;64302.67848660727;64317.67841160858;64571.03143452758;64708;66076.00885986714;66098.6780264392;66123.33876661172;66141;16980;17220.31118755261;17241.65516689659;17243.3964811231;17244.827551724575;11824.467898944567;11988.400579971141;12779.872201277869;12977.35113244334;13022.869771302356;17065.7440138398;20412.285570004977;20521.692174617394;20603;20613.58772824565;7348.96325518369;8228.794280143044;8341.5412152331;8479.491230526188;8483.182567844371;926.9953650231706;948.8671585977969;1011.3729487717662;1068.1881769854965;1083.8916108389255;30178.09465716032;30427.847860760554;30789;31007.534886976715;31013.51891848647;770.6416516319827;1138.7950168969498;1169.3977601535216;1207.782599132147;1217.8843009914044;13442.46230150783;15141.924290378478;15233.847661523243;15453;15511.922440387725;483666;487742;491303;491948;492100;62697.68651156715;63320;63320.68339658364;63460;63490;1177.4466000979526;1305.4386613755987;1485.5097817720343;1508.351408894186;1528.205333226738;29490.705092948796;29526.704732953254;29806.552901706058;30254.243643909078;30319.69680303257;49952.75023624858;50416.24375634442;50442.2433663488;50473;50486.46594738333;354328;358359;358983;359203;360122;26710.465790683687;28532.714672853836;28540.143795686163;28547;28580.571291430228;44184.779076104416;45174.09651807011;45792.77103614461;45839.31241031321;46421.83945401257;36847.815760921025;37411;37654;37916.43125353177;38794.80602596969;36436.27127457489;37100.44349334816;37443.43834842425;37728.245435091696;37790.811045944596;36431;36449.635503644626;37113.814430927676;37152.628473715995;37721.24557508889;32972.1756956078;34674.47988280127;34680.826595866856;34710.47934281038;34847.47728784121;25374.492510150063;25468.33295916571;25552.488950221265;25625.10312139066;26043.088491902694 02:18:22 INFO - PROCESS | 1953 | |2;jquery.html;347.44235502019257;407.4967415242173;407.9041425265063;409.8278722936368;416.68332067628614;14890.329935152917;20835.791642083575;21238;21596.460088497777;21621.891890540544;15868.523944281662;18546.350877719284;20842.270520531783;20930.476738081554;21366.35900922971;5919.112133180014;6128.019516877278;6138.324784273726;6142.692865356737;6150.0159974404105;5927.347991720907;6006.789762358297;6015.218021657179;6025.096235564656;6076.787312444066;5799.65202087874;5915.497182739472;5934.198883150784;5937.643741375509;5940.851478713023;5840.299164100293;5905.586608937376;5910.468057874769;5910.527157827385;5945.732442040118;5045.293658887758;5193.376794784613;5196.012757576049;5213.608979326527;5219.19102539107;25125.497490050097;26159.869200653873;26445.735542644714;27327.86336068307;27420.90316387342;2324.360800779782;2362.2795047510504;2368.0764501844296;2392.377981724747;2683.6242925990377;14372.35324410404;15135.924320378437;15364.615884602863;15413.691726165414;15781.28984195714;1664.1595994022941;1732.1079643983358;1735.5834599695982;1782.3761683410703;1817.4184261036473;123128;123476.76523234743;126114.73885261214;127454.72545274522;128662;10755.300905441121;11156.721081973015;11374.56392007118;11415.42922853854;11467.426628668532;14538.27308634564;14622.415103395957;14650.120992740469;14651.267436628126;14683.55949321522;6771;7825.843483130306;8580.442271252474;8827.602757875911;9007.414518056305;7335.339819416278;8991.505467199237;8992.46045237288;9018.729438116867;9063.81872362549;6737.595744255263;6847.432212920155;6873.896891546631;6880.69036893341;6918.757843475454;1886.3114963038392;1905.694599199525;1922.557811703288;1922.6346994071082;1929.8938558379143;920.30516959695;932.6922115701802;963.210167662524;1013.964511242103;1084.436093231531;20071.899640501702;21254.545274400643;22215.778132202562;22245.55508889813;22336.553268934855;963.6675347005272;979.1530326267728;1010.1363334349172;1017.9083882450615;1085.6146068145695;11049.392283424328;11090.57485963192;11196.9362910525;11258.155638327158;11279.379634120201;590570;607369.9263007402;643281;645736;652452;127063;128205.71794282124;131276.0308595372;133723;135704;1068.5191663751343;1075.3279200499687;1098.7253186703324;1100.8568886044764;1103.9944800275948;18110.81889181091;18613.255469781056;18784.343056594367;18893.055347232857;19191.80808191956;48934.75532622314;50523.49476505188;50899.236511451614;51509;53853.46146538641;76127;77240.6137969329;78049.0487737811;78694;80525.19474805411;8173.5504547250475;8285.4200205985;8297.464968980757;8515.574221289035;8533.74398768013;10955.34267943926;11929.701757456132;12068.155229134227;13014.544490942772;13158.078934474495;10935.507902144263;11225.214235003472;11227.96844851869;11247.931446512568;11248.662540123809;9138.908610913806;10757.246992710436;10897.29167604135;10960.945195273973;11067.391293478937;10482.689663792025;10597.152227821602;11239.66281011571;11299.887001130212;11863.822042669539;8150.918490815253;8158.184181581794;8192.385571082074;8472;8494.150584941457;8505;10665.89334106649;11023.503942322453;11280.717982050515;11548.826767598324 02:18:22 INFO - PROCESS | 1953 | |3;mootools.html;1389.7081612861298;1578.810542734872;1615.2811998660595;1778.3864566724476;1903.6954087346019;7934.44458887878;8835;9015.729528114167;9018;9019.594118264677;8568.957155214232;8833.955830220855;8845.513496757676;8847.071057538962;8848.380613357067;2434.196715084027;2459.348272707734;2463.950720985579;2464.0759715106833;2471.8146139039554;2409.132712223598;2462.052109937669;2463.6674049003336;2465.617829236471;2469.074097213545;2417.1419146203107;2439.841410308324;2454.6808914841135;2458.913938012161;2462.261321603512;2404.1825779235055;2427.356750461129;2433.3551608823677;2438.756124387565;2440.2069327468553;2369.312899259213;2403.735589085199;2404.326788499223;2407.3981504623844;2407.650890620868;7517.586532740697;8740.51927144007;8832.02847686754;8836.734897953073;8839.823203535958;5264.184051472028;5440.591955603346;5479.260299859548;5489.945100548984;5500.972495137538;3427.2117412994903;3506.6142724300307;3582.3551760683067;3584.9283014339826;3593.4969104325414;1032.550840384435;1039.5270152080784;1042.7705904700954;1045.6915210013065;1060.083028180626;11745.765084698258;11842.703932401673;11878.584249551224;11888.465019074163;11910.285382877055;4556.2937744649635;4616.146012987608;4687.695299805502;4709.905801884011;4716.174669432836;9307.395019323721;9311.90688093124;9312.813743725088;9313.20837728787;9330.300227482965;5216.4522725114075;5299.5760339172775;5311.123664595299;5332.440093790096;5353.2773075634295;4429.2027435062;4485.259932111165;4651.441826980784;4938.851834444972;4973.925391119136;3228.3059142284524;3330.7501937354796;3856.672182864488;3898.6101389861356;3929.4302326162547;1042.8748550174028;1199.2264989082169;1209.0629761934572;1215.9025680700897;1218.3786269002871;859.1236938323001;889.4707648948994;901.7520181950016;910.6721580231111;928.7445952363154;5936.22829032223;6057.091436284504;6115.663638499836;6192.690365481708;6238.906416403757;906.5059542549195;928.2620316848122;934.6915517879083;962.3936919740659;974.9268804839666;3423.845926933294;3993.2412841560013;4008.8396464141106;4058.2695114879607;4115.650169735606;10429.478526073666;10864.565417383215;10909;11010.944945275221;11030.172737044753;10841.83737243942;10854.67435976922;10864.239503234698;10870.891291087146;10987.340759554452;7945.284924356835;8114.066882308492;8313.210245026712;8337.833243335099;8411.747647570583;5916.645001299936;5934.673592952429;6005.339412664516;6099.9390006099375;6138.662373569497;9712.125908668253;10097.394156350643;10198.439085850052;10209.693709188736;10217.795644086927;9636.662716804878;9949.651762188289;10065.496725163566;10226.437545934747;10354.637587684696;6713.1944166700305;7430.628468576658;7530.6611202497115;7587.810304742426;7674.424418168548;8153.6330865112395;8257.298129658806;8349.707760228363;8360.749177524684;8366.790830229293;7460.2912723293375;7467.813304667427;7515.323620874147;7597.392208623407;7616.23837616234;7314.305141011595;7423.443241756998;7458.925410745823;7513.511621744569;7559;7021.473389495911;7027.086478757728;7081;7102.360787529147;7115.181754098345;4547.022390185994;4902.7058376497525;4942.703437793743;4983.07813054586;5171.146760784429;5035.546800787946;5063.063333283354;5072.644914856072;5142.357205349332;5272.209168624584 02:18:22 INFO - PROCESS | 1953 | |4;prototype.html;314.131426605436;314.43401876622085;319.16856588586717;322.9208843833261;325.95762550868386;4642.466116396616;5280.762365693543;5314.920276195855;5315.840524784253;5317.202419637057;4091.4067460218253;4358.673099517541;4390.956090439095;4483.439570053743;4630.907381852361;3810.5427348718194;3879.6809084911124;3916.5300163980364;3975.085730282037;4011.077452185999;3533.628968958261;3842.1931394407206;4067.2882245607047;4068.5524592294823;4070.2470043041985;3783.4892289540835;4006.6794656427414;4015.1969606078756;4021.3163762160357;4023.2154729827694;3861.655637407916;3904.9023774405723;4061.228366610364;4061.6547593454593;4076.2051399977017;2984.552666813214;3215.5176723491663;3565.6434356564396;3573.6605022522817;3587.1390866192187;5025.673331233458;5061.696298222082;5067.265246539268;5093.617978651616;5135.948640513584;4289.742615443083;4530.705504142253;4616.561426664462;4642.303654451859;4665.743384113873;4569.337446070334;4647.55848194421;4742.95257047432;5299.8410047698635;5528.889422211573;3351.927383237365;3658.615845336228;3767.6609105180664;3924.548676902164;3960.2277555876617;4905.558499735041;5233.816816411408;5769.576777753354;5795.507381872546;5856.531477481792;3364.7644664873715;3958.44581758554;3985.442038114666;3992.181602771425;4017.6986725995084;3417.7607567470536;3586.372384832644;4062.2281766464275;4065.5324637666454;4068.5524592294823;4477.664175186809;4625.190591646448;4642.16441040616;4653.8132776141865;4661.650376221797;4990.251462280615;5478.479544443351;5541.639793413416;5550.972245138749;5574.554035677136;1230.3110258255406;1313.5139998200727;1438.8848892088608;1454.7672372420573;1461.189040082743;794.940379471542;864.7492227254025;961.5336561767538;988.7182153086336;991.8016396720541;4370;4693.310408253026;5536.4186760390385;5555.222388805597;5580.581456390787;5656.688882111441;6173.351798061229;6194.349593292639;6194.946859033975;6237.4698150657705;4733.2900064989835;5511.614187006951;5578.44215578447;5579.995600791896;5602.915956260578;2758.3379988802544;3185.9804862444257;3197.928693887574;3233.692799184074;3241.9837900810344;5905.734241959122;6118.388161183935;6405.487560995202;6415.775447859396;6428.260750013715;6248.218972628421;6268.811935641938;6275.6862156892885;6287.874242515124;6291.465225455796;5091.541761241506;5307.7080760557765;6397.96801015992;6399.472208477625;6406.263279722705;5930.881382372242;6087.57386982907;6109.800471830049;6127.234095738033;6152.507799376129;5216.452272511332;5334.359876814807;5533.889322213453;6026.216591843032;6104.938950610437;4981.850544483671;5169.715665638426;6361.173047503795;6414.711337989708;6419.4864410846085;3558.626344233818;4192.568310380033;4212.326027835532;4234.470691163605;4265.87202383929;5043.394792624908;5841.6856207353;5909.674967876808;6114.2357205349335;6145.569810113428;5115.360579927509;5174.818881339135;5783;5912.940870591239;5925.674087925206;5193.76628051731;5380.488853558905;5802.883942321215;5865.354810970875;5886.293644762656;3919.3141200289265;5337.919931201112;5739.368669446274;5794.188813566188;5895.145203945318;4645.49021567985;5155.845324640267;5317.8462722597405;5544.1960915666195;5562.638428502134;5040.7479626019285;5405.648632838853;5425.996190704732;5452.454754524516;5458.454154584669 02:18:22 INFO - PROCESS | 1953 | |5;yui.html;711.5730561663006;725.4667819152925;732.2750477027743;764.5871229536051;773.3581127664039;14413.05438166984;14540.563783086498;15371.615709607266;15383.077015379085;15385.69228615427;13935.930320348409;14040.859591404083;14070.94467914908;14095.57713268601;14106.647333816634;735.2537174767615;915.624593916496;934.042606328512;935.0836180543072;935.6771913689767;746.6192241956587;899.1503029636996;927.0821886332516;927.1238679447924;927.8840144981878;956.8277710012195;958.3674774648733;966.830804609194;967.7822489939778;968.6222373274429;763.6868883757633;959.1176117971482;961.1637875048717;961.995190024049;962.2398305338774;572.1846368924266;581.2647001543052;581.3750218515113;705.0869124483805;723.1972510513326;14794.704105917928;14831.406743730238;14839.406423743038;14843.703125937422;14861.405543778235;12309.322987235699;12422.378881056002;12627.873721262762;12664.81002784959;12740.872591274061;9757.853632195523;9914.305998580103;10023.649172279009;10039.74900627483;10684.19868509857;3430.8456119474686;3453.4819777033395;3480.738944579167;3483.8432270547887;3565.8217089145437;76935;77622.22377776206;79203.20796792016;79349.80975285375;79959.60020199862;9528.952355238318;9648.71053868385;9808.705738827846;9821.36161149523;9853;22082.22712205065;22104.778952210592;22133.557328853334;22147;22150.33548993533;6825.112735344473;7255.129384473791;7389.113306403158;7405.555666660018;7609.923900761032;3999;4081.89795255121;4175.164967006611;4195.727277726938;4357.411749413852;3002.9849850750606;3606;3614.0784100054843;3616.457531370263;3622.003948914017;623.8066199478086;645.3772109913956;645.8934275844528;651.8989556618732;664.3655309179721;495.66294919454396;561.943805619443;587.4301927130676;588.9469947704728;591.7987884119334;7946.688796348653;8172.264496195371;8182.549959752271;8212.297476096539;8276.213759692819;570.5293133165155;579.8724280658248;598.1625723986367;598.7844376024627;602.8040886711749;3794.962050379516;3855.479510266134;3856.9421458678144;3942.7240093193773;3953.8418463261146;97555.51222243985;98305.5084724586;98985.01014989903;100588;101017.47456313504;75909.48181036349;77117.45765084779;78343;79539;84107;72370.1907452318;72993.6350318245;73044.90432643409;73052.63473682704;73120;7186.676599553137;7471.92528074734;7665.770026899202;7761.417893658111;7931.881021784793;42713.718588442396;43479.9130021752;43807.78096109499;44374.778126109166;44520.999999998705;61114.38885611087;63963.68018159879;64207.71584568376;64522;64833;10516.474176291307;10556;10645.89354106449;10703.892961070602;10727.088197503299;10800.513976870903;10965.50655220533;11037.172212083966;11067.778644427228;11709.180357374902;9987.350822196535;10227.48862556884;10274.33216840903;10531.315464494783;10648.787024259316;10271.94864025675;10306.12397946183;10533.36799792015;10626.309289896131;10646.73383165427;9980.101790838859;10028.749281268027;10175.28772985884;10329.9483502582;10595.993380628828;8893.555322233991;9111.453312801254;9210.217131543894;9316.114969077928;9713.854292185764;8971.237444817263;9360.812783744152;9426.293028023063;9484;9572.186364158846 02:18:22 INFO - PROCESS | 1953 | __end_tp_report 02:18:22 INFO - PROCESS | 1953 | __start_cc_report 02:18:22 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,1250 02:18:22 INFO - PROCESS | 1953 | __end_cc_report 02:18:22 INFO - PROCESS | 1953 | __startTimestamp1462094302812__endTimestamp 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | ------- Summary: start ------- 02:18:22 INFO - PROCESS | 1953 | Number of tests: 6 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | [#0] dojo.html Cycles:165 Average:5697.27 Median:5916.91 stddev:642.35 (10.9%) stddev-sans-first:611.74 02:18:22 INFO - PROCESS | 1953 | Values: 3122.6 3424.6 3425.8 3431.2 3469.6 5660.6 5669.9 5670.1 5677.8 5687.8 5663.9 5694.3 5695.5 5696.7 5699.8 5079.0 5119.0 5143.2 5162.3 5184.6 5204.7 5211.9 5217.7 5219.5 5226.5 5111.1 5156.6 5179.4 5192.3 5202.9 5190.9 5207.3 5209.0 5235.3 5245.9 4316.5 5014.0 5041.5 5121.5 5142.6 5521.4 5529.5 5548.5 5588.8 5654.7 6180.5 6208.0 6210.4 6222.8 6226.2 6146.9 6164.7 6232.9 6266.7 6286.0 5781.6 5795.9 5796.1 5797.7 5838.6 6307.4 6312.0 6313.4 6313.7 6319.9 5158.6 6019.8 6104.5 6163.2 6168.7 6057.9 6063.5 6064.1 6080.8 6142.0 5969.7 5981.7 5984.2 6011.2 6020.5 5997.4 6007.8 6153.9 6154.5 6154.9 4901.7 4962.4 4972.9 4989.3 5007.1 4345.4 4395.3 4402.8 4409.1 4444.5 5526.0 5638.2 5667.9 5679.4 5696.1 6175.4 6278.4 6298.4 6303.7 6313.9 5643.1 5660.1 5665.1 5669.9 5671.1 5245.4 5332.9 5340.9 5390.8 5392.2 6335.5 6409.7 6411.5 6417.4 6430.3 6263.2 6264.0 6309.7 6313.1 6319.4 6236.3 6255.7 6390.1 6406.4 6411.7 5980.0 6016.1 6025.8 6068.2 6068.3 5286.5 6164.9 6173.2 6176.9 6181.7 5882.6 5894.3 5939.8 6042.6 6140.8 6124.4 6127.7 6134.3 6141.2 6148.5 5912.6 5971.9 5978.6 5979.9 5987.1 5899.6 5916.9 6101.4 6104.8 6120.7 5908.9 5938.2 5942.0 5962.2 5964.8 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | [#1] ext.html Cycles:180 Average:57372.78 Median:34695.65 stddev:109142.87 (314.6%) stddev-sans-first:109365.51 02:18:22 INFO - PROCESS | 1953 | Values: 499.1 507.4 514.4 521.1 560.9 29180.3 36399.0 40600.8 40768.6 40790.4 42584.0 43142.8 43239.3 43249.6 43496.9 8613.5 8814.1 9660.9 9874.5 9876.0 11503.0 11698.5 11729.6 11730.8 11778.9 11749.5 11940.6 11954.3 11955.2 11957.5 8643.5 11599.8 11695.4 11836.5 11849.0 11637.4 11800.1 11804.3 11806.8 11833.9 41328.2 42029.6 42080.6 42089.6 42171.6 2884.0 3195.6 3238.3 3289.4 3392.7 27674.0 28057.0 28401.7 29217.9 30585.8 2706.2 2706.6 2737.9 2796.0 2801.8 322797.0 390669.0 391592.0 392383.0 393536.0 62950.7 64207.7 64302.7 64317.7 64571.0 64708.0 66076.0 66098.7 66123.3 66141.0 16980.0 17220.3 17241.7 17243.4 17244.8 11824.5 11988.4 12779.9 12977.4 13022.9 17065.7 20412.3 20521.7 20603.0 20613.6 7349.0 8228.8 8341.5 8479.5 8483.2 927.0 948.9 1011.4 1068.2 1083.9 30178.1 30427.8 30789.0 31007.5 31013.5 770.6 1138.8 1169.4 1207.8 1217.9 13442.5 15141.9 15233.8 15453.0 15511.9 483666.0 487742.0 491303.0 491948.0 492100.0 62697.7 63320.0 63320.7 63460.0 63490.0 1177.4 1305.4 1485.5 1508.4 1528.2 29490.7 29526.7 29806.6 30254.2 30319.7 49952.8 50416.2 50442.2 50473.0 50486.5 354328.0 358359.0 358983.0 359203.0 360122.0 26710.5 28532.7 28540.1 28547.0 28580.6 44184.8 45174.1 45792.8 45839.3 46421.8 36847.8 37411.0 37654.0 37916.4 38794.8 36436.3 37100.4 37443.4 37728.2 37790.8 36431.0 36449.6 37113.8 37152.6 37721.2 32972.2 34674.5 34680.8 34710.5 34847.5 25374.5 25468.3 25552.5 25625.1 26043.1 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | [#2] jquery.html Cycles:180 Average:36318.47 Median:22230.67 stddev:104809.59 (471.5%) stddev-sans-first:105068.81 02:18:22 INFO - PROCESS | 1953 | Values: 347.4 407.5 407.9 409.8 416.7 14890.3 20835.8 21238.0 21596.5 21621.9 15868.5 18546.4 20842.3 20930.5 21366.4 5919.1 6128.0 6138.3 6142.7 6150.0 5927.3 6006.8 6015.2 6025.1 6076.8 5799.7 5915.5 5934.2 5937.6 5940.9 5840.3 5905.6 5910.5 5910.5 5945.7 5045.3 5193.4 5196.0 5213.6 5219.2 25125.5 26159.9 26445.7 27327.9 27420.9 2324.4 2362.3 2368.1 2392.4 2683.6 14372.4 15135.9 15364.6 15413.7 15781.3 1664.2 1732.1 1735.6 1782.4 1817.4 123128.0 123476.8 126114.7 127454.7 128662.0 10755.3 11156.7 11374.6 11415.4 11467.4 14538.3 14622.4 14650.1 14651.3 14683.6 6771.0 7825.8 8580.4 8827.6 9007.4 7335.3 8991.5 8992.5 9018.7 9063.8 6737.6 6847.4 6873.9 6880.7 6918.8 1886.3 1905.7 1922.6 1922.6 1929.9 920.3 932.7 963.2 1014.0 1084.4 20071.9 21254.5 22215.8 22245.6 22336.6 963.7 979.2 1010.1 1017.9 1085.6 11049.4 11090.6 11196.9 11258.2 11279.4 590570.0 607369.9 643281.0 645736.0 652452.0 127063.0 128205.7 131276.0 133723.0 135704.0 1068.5 1075.3 1098.7 1100.9 1104.0 18110.8 18613.3 18784.3 18893.1 19191.8 48934.8 50523.5 50899.2 51509.0 53853.5 76127.0 77240.6 78049.0 78694.0 80525.2 8173.6 8285.4 8297.5 8515.6 8533.7 10955.3 11929.7 12068.2 13014.5 13158.1 10935.5 11225.2 11228.0 11247.9 11248.7 9138.9 10757.2 10897.3 10960.9 11067.4 10482.7 10597.2 11239.7 11299.9 11863.8 8150.9 8158.2 8192.4 8472.0 8494.2 8505.0 10665.9 11023.5 11280.7 11548.8 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | [#3] mootools.html Cycles:180 Average:5732.15 Median:5009.31 stddev:3205.78 (64.0%) stddev-sans-first:3198.17 02:18:22 INFO - PROCESS | 1953 | Values: 1389.7 1578.8 1615.3 1778.4 1903.7 7934.4 8835.0 9015.7 9018.0 9019.6 8569.0 8834.0 8845.5 8847.1 8848.4 2434.2 2459.3 2464.0 2464.1 2471.8 2409.1 2462.1 2463.7 2465.6 2469.1 2417.1 2439.8 2454.7 2458.9 2462.3 2404.2 2427.4 2433.4 2438.8 2440.2 2369.3 2403.7 2404.3 2407.4 2407.7 7517.6 8740.5 8832.0 8836.7 8839.8 5264.2 5440.6 5479.3 5489.9 5501.0 3427.2 3506.6 3582.4 3584.9 3593.5 1032.6 1039.5 1042.8 1045.7 1060.1 11745.8 11842.7 11878.6 11888.5 11910.3 4556.3 4616.1 4687.7 4709.9 4716.2 9307.4 9311.9 9312.8 9313.2 9330.3 5216.5 5299.6 5311.1 5332.4 5353.3 4429.2 4485.3 4651.4 4938.9 4973.9 3228.3 3330.8 3856.7 3898.6 3929.4 1042.9 1199.2 1209.1 1215.9 1218.4 859.1 889.5 901.8 910.7 928.7 5936.2 6057.1 6115.7 6192.7 6238.9 906.5 928.3 934.7 962.4 974.9 3423.8 3993.2 4008.8 4058.3 4115.7 10429.5 10864.6 10909.0 11010.9 11030.2 10841.8 10854.7 10864.2 10870.9 10987.3 7945.3 8114.1 8313.2 8337.8 8411.7 5916.6 5934.7 6005.3 6099.9 6138.7 9712.1 10097.4 10198.4 10209.7 10217.8 9636.7 9949.7 10065.5 10226.4 10354.6 6713.2 7430.6 7530.7 7587.8 7674.4 8153.6 8257.3 8349.7 8360.7 8366.8 7460.3 7467.8 7515.3 7597.4 7616.2 7314.3 7423.4 7458.9 7513.5 7559.0 7021.5 7027.1 7081.0 7102.4 7115.2 4547.0 4902.7 4942.7 4983.1 5171.1 5035.5 5063.1 5072.6 5142.4 5272.2 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | [#4] prototype.html Cycles:180 Average:4605.49 Median:5064.48 stddev:1453.68 (28.7%) stddev-sans-first:1421.62 02:18:22 INFO - PROCESS | 1953 | Values: 314.1 314.4 319.2 322.9 326.0 4642.5 5280.8 5314.9 5315.8 5317.2 4091.4 4358.7 4391.0 4483.4 4630.9 3810.5 3879.7 3916.5 3975.1 4011.1 3533.6 3842.2 4067.3 4068.6 4070.2 3783.5 4006.7 4015.2 4021.3 4023.2 3861.7 3904.9 4061.2 4061.7 4076.2 2984.6 3215.5 3565.6 3573.7 3587.1 5025.7 5061.7 5067.3 5093.6 5135.9 4289.7 4530.7 4616.6 4642.3 4665.7 4569.3 4647.6 4743.0 5299.8 5528.9 3351.9 3658.6 3767.7 3924.5 3960.2 4905.6 5233.8 5769.6 5795.5 5856.5 3364.8 3958.4 3985.4 3992.2 4017.7 3417.8 3586.4 4062.2 4065.5 4068.6 4477.7 4625.2 4642.2 4653.8 4661.7 4990.3 5478.5 5541.6 5551.0 5574.6 1230.3 1313.5 1438.9 1454.8 1461.2 794.9 864.7 961.5 988.7 991.8 4370.0 4693.3 5536.4 5555.2 5580.6 5656.7 6173.4 6194.3 6194.9 6237.5 4733.3 5511.6 5578.4 5580.0 5602.9 2758.3 3186.0 3197.9 3233.7 3242.0 5905.7 6118.4 6405.5 6415.8 6428.3 6248.2 6268.8 6275.7 6287.9 6291.5 5091.5 5307.7 6398.0 6399.5 6406.3 5930.9 6087.6 6109.8 6127.2 6152.5 5216.5 5334.4 5533.9 6026.2 6104.9 4981.9 5169.7 6361.2 6414.7 6419.5 3558.6 4192.6 4212.3 4234.5 4265.9 5043.4 5841.7 5909.7 6114.2 6145.6 5115.4 5174.8 5783.0 5912.9 5925.7 5193.8 5380.5 5802.9 5865.4 5886.3 3919.3 5337.9 5739.4 5794.2 5895.1 4645.5 5155.8 5317.8 5544.2 5562.6 5040.7 5405.6 5426.0 5452.5 5458.5 02:18:22 INFO - PROCESS | 1953 | 02:18:22 INFO - PROCESS | 1953 | [#5] yui.html Cycles:180 Average:18111.99 Median:30858.60 stddev:26019.21 (84.3%) stddev-sans-first:26059.40 02:18:22 INFO - PROCESS | 1953 | Values: 711.6 725.5 732.3 764.6 773.4 14413.1 14540.6 15371.6 15383.1 15385.7 13935.9 14040.9 14070.9 14095.6 14106.6 735.3 915.6 934.0 935.1 935.7 746.6 899.2 927.1 927.1 927.9 956.8 958.4 966.8 967.8 968.6 763.7 959.1 961.2 962.0 962.2 572.2 581.3 581.4 705.1 723.2 14794.7 14831.4 14839.4 14843.7 14861.4 12309.3 12422.4 12627.9 12664.8 12740.9 9757.9 9914.3 10023.6 10039.7 10684.2 3430.8 3453.5 3480.7 3483.8 3565.8 76935.0 77622.2 79203.2 79349.8 79959.6 9529.0 9648.7 9808.7 9821.4 9853.0 22082.2 22104.8 22133.6 22147.0 22150.3 6825.1 7255.1 7389.1 7405.6 7609.9 3999.0 4081.9 4175.2 4195.7 4357.4 3003.0 3606.0 3614.1 3616.5 3622.0 623.8 645.4 645.9 651.9 664.4 495.7 561.9 587.4 588.9 591.8 7946.7 8172.3 8182.5 8212.3 8276.2 570.5 579.9 598.2 598.8 602.8 3795.0 3855.5 3856.9 3942.7 3953.8 97555.5 98305.5 98985.0 100588.0 101017.5 75909.5 77117.5 78343.0 79539.0 84107.0 72370.2 72993.6 73044.9 73052.6 73120.0 7186.7 7471.9 7665.8 7761.4 7931.9 42713.7 43479.9 43807.8 44374.8 44521.0 61114.4 63963.7 64207.7 64522.0 64833.0 10516.5 10556.0 10645.9 10703.9 10727.1 10800.5 10965.5 11037.2 11067.8 11709.2 9987.4 10227.5 10274.3 10531.3 10648.8 10271.9 10306.1 10533.4 10626.3 10646.7 9980.1 10028.7 10175.3 10329.9 10596.0 8893.6 9111.5 9210.2 9316.1 9713.9 8971.2 9360.8 9426.3 9484.0 9572.2 02:18:22 INFO - PROCESS | 1953 | -------- Summary: end -------- 02:18:22 INFO - PROCESS | 1953 | 02:18:23 INFO - TEST-INFO | 1953: exit 0 02:18:23 INFO - TEST-OK | dromaeo_css | took 1114234ms 02:18:23 INFO - TEST-START | kraken 02:18:23 INFO - operating with platform_type : mac_ 02:18:23 INFO - Initialising browser for kraken test... 02:18:23 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpw6xOSs/profile http://localhost:54202/getInfo.html) 02:18:25 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 02:18:25 INFO - PROCESS | 1957 | colorDepth:24 02:18:25 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 02:18:25 INFO - PROCESS | 1957 | __metrics 02:18:25 INFO - PROCESS | 1957 | 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" 02:18:25 ERROR - PROCESS | 1957 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:18:26 INFO - TEST-INFO | 1957: exit 0 02:18:26 INFO - Browser initialized. 02:18:26 INFO - Running cycle 1/1 for kraken test... 02:18:26 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpw6xOSs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:18:38 INFO - PROCESS | 1960 | RSS: Main: 211468288 02:18:38 INFO - PROCESS | 1960 | 02:18:41 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54202/tests/kraken/driver.html?testName=audio-beat-detection) 02:18:42 INFO - PROCESS | 1960 | RSS: Main: 270524416 02:18:42 INFO - PROCESS | 1960 | 02:18:44 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54202/tests/kraken/driver.html?testName=audio-dft) 02:18:45 INFO - PROCESS | 1960 | RSS: Main: 284225536 02:18:45 INFO - PROCESS | 1960 | 02:18:48 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54202/tests/kraken/driver.html?testName=audio-fft) 02:18:49 INFO - PROCESS | 1960 | RSS: Main: 372912128 02:18:49 INFO - PROCESS | 1960 | 02:18:51 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54202/tests/kraken/driver.html?testName=audio-oscillator) 02:18:51 INFO - PROCESS | 1960 | RSS: Main: 277372928 02:18:51 INFO - PROCESS | 1960 | 02:18:54 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54202/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:18:54 INFO - PROCESS | 1960 | RSS: Main: 279752704 02:18:54 INFO - PROCESS | 1960 | 02:18:57 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54202/tests/kraken/driver.html?testName=imaging-darkroom) 02:18:58 INFO - PROCESS | 1960 | RSS: Main: 317792256 02:18:58 INFO - PROCESS | 1960 | 02:19:01 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54202/tests/kraken/driver.html?testName=imaging-desaturate) 02:19:01 INFO - PROCESS | 1960 | RSS: Main: 317612032 02:19:01 INFO - PROCESS | 1960 | 02:19:04 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54202/tests/kraken/driver.html?testName=json-parse-financial) 02:19:05 INFO - PROCESS | 1960 | RSS: Main: 317997056 02:19:05 INFO - PROCESS | 1960 | 02:19:06 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54202/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:19:07 INFO - PROCESS | 1960 | RSS: Main: 307265536 02:19:07 INFO - PROCESS | 1960 | 02:19:09 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:19:10 INFO - PROCESS | 1960 | RSS: Main: 313651200 02:19:10 INFO - PROCESS | 1960 | 02:19:12 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:19:12 INFO - PROCESS | 1960 | RSS: Main: 316760064 02:19:12 INFO - PROCESS | 1960 | 02:19:15 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:19:16 INFO - PROCESS | 1960 | RSS: Main: 322236416 02:19:16 INFO - PROCESS | 1960 | 02:19:19 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:19:19 INFO - PROCESS | 1960 | RSS: Main: 319885312 02:19:19 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54202/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54202/tests/kraken/driver.html?testName=ai-astar) 02:19:21 INFO - PROCESS | 1960 | RSS: Main: 384413696 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | __start_tp_report 02:19:21 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 02:19:21 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 02:19:21 INFO - PROCESS | 1960 | |i|pagename|runs| 02:19:21 INFO - PROCESS | 1960 | |0;ai-astar;114;113;108;112;115;114;114;112;104;113 02:19:21 INFO - PROCESS | 1960 | |1;audio-beat-detection;154;121;160;177;141;151;137;124;191;142 02:19:21 INFO - PROCESS | 1960 | |2;audio-dft;186;185;189;183;186;183;184;184;186;184 02:19:21 INFO - PROCESS | 1960 | |3;audio-fft;72;78;72;152;56;55;56;69;129;56 02:19:21 INFO - PROCESS | 1960 | |4;audio-oscillator;90;88;148;76;82;85;114;108;107;146 02:19:21 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;89;86;90;86;89;86;89;84;126;85 02:19:21 INFO - PROCESS | 1960 | |6;imaging-darkroom;124;146;146;95;95;100;94;95;94;94 02:19:21 INFO - PROCESS | 1960 | |7;imaging-desaturate;86;86;86;94;137;128;86;85;92;135 02:19:21 INFO - PROCESS | 1960 | |8;json-parse-financial;46;54;88;91;90;64;64;86;60;59 02:19:21 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;63;64;71;73;75;71;68;67;69;68 02:19:21 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;81;82;80;80;81;80;80;81;82;86 02:19:21 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;149;149;125;151;143;146;131;136;175;135 02:19:21 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;165;166;176;149;189;214;180;167;166;167 02:19:21 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;68;65;69;70;68;66;69;70;68;66 02:19:21 INFO - PROCESS | 1960 | __end_tp_report 02:19:21 INFO - PROCESS | 1960 | __start_cc_report 02:19:21 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,2032 02:19:21 INFO - PROCESS | 1960 | __end_cc_report 02:19:21 INFO - PROCESS | 1960 | __startTimestamp1462094361767__endTimestamp 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | ------- Summary: start ------- 02:19:21 INFO - PROCESS | 1960 | Number of tests: 14 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.50 stddev:3.38 (3.0%) stddev-sans-first:3.50 02:19:21 INFO - PROCESS | 1960 | Values: 114.0 113.0 108.0 112.0 115.0 114.0 114.0 112.0 104.0 113.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:152.50 stddev:22.00 (14.4%) stddev-sans-first:23.29 02:19:21 INFO - PROCESS | 1960 | Values: 154.0 121.0 160.0 177.0 141.0 151.0 137.0 124.0 191.0 142.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:1.83 (1.0%) stddev-sans-first:1.90 02:19:21 INFO - PROCESS | 1960 | Values: 186.0 185.0 189.0 183.0 186.0 183.0 184.0 184.0 186.0 184.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:79.50 Median:62.50 stddev:33.65 (53.8%) stddev-sans-first:35.58 02:19:21 INFO - PROCESS | 1960 | Values: 72.0 78.0 72.0 152.0 56.0 55.0 56.0 69.0 129.0 56.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:104.40 Median:79.00 stddev:25.61 (32.4%) stddev-sans-first:26.63 02:19:21 INFO - PROCESS | 1960 | Values: 90.0 88.0 148.0 76.0 82.0 85.0 114.0 108.0 107.0 146.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:87.50 stddev:12.46 (14.2%) stddev-sans-first:13.20 02:19:21 INFO - PROCESS | 1960 | Values: 89.0 86.0 90.0 86.0 89.0 86.0 89.0 84.0 126.0 85.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:94.00 stddev:21.86 (23.3%) stddev-sans-first:22.44 02:19:21 INFO - PROCESS | 1960 | Values: 124.0 146.0 146.0 95.0 95.0 100.0 94.0 95.0 94.0 94.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:101.50 Median:86.00 stddev:22.27 (25.9%) stddev-sans-first:22.91 02:19:21 INFO - PROCESS | 1960 | Values: 86.0 86.0 86.0 94.0 137.0 128.0 86.0 85.0 92.0 135.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:75.00 stddev:16.82 (22.4%) stddev-sans-first:15.39 02:19:21 INFO - PROCESS | 1960 | Values: 46.0 54.0 88.0 91.0 90.0 64.0 64.0 86.0 60.0 59.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:70.00 stddev:3.75 (5.4%) stddev-sans-first:3.32 02:19:21 INFO - PROCESS | 1960 | Values: 63.0 64.0 71.0 73.0 75.0 71.0 68.0 67.0 69.0 68.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.00 stddev:1.83 (2.3%) stddev-sans-first:1.94 02:19:21 INFO - PROCESS | 1960 | Values: 81.0 82.0 80.0 80.0 81.0 80.0 80.0 81.0 82.0 86.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:147.50 stddev:13.90 (9.4%) stddev-sans-first:14.63 02:19:21 INFO - PROCESS | 1960 | Values: 149.0 149.0 125.0 151.0 143.0 146.0 131.0 136.0 175.0 135.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:171.50 stddev:17.63 (10.3%) stddev-sans-first:18.40 02:19:21 INFO - PROCESS | 1960 | Values: 165.0 166.0 176.0 149.0 189.0 214.0 180.0 167.0 166.0 167.0 02:19:21 INFO - PROCESS | 1960 | 02:19:21 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.50 stddev:1.73 (2.5%) stddev-sans-first:1.83 02:19:21 INFO - PROCESS | 1960 | Values: 68.0 65.0 69.0 70.0 68.0 66.0 69.0 70.0 68.0 66.0 02:19:21 INFO - PROCESS | 1960 | -------- Summary: end -------- 02:19:21 INFO - PROCESS | 1960 | 02:19:22 INFO - TEST-INFO | 1960: exit 0 02:19:22 INFO - TEST-OK | kraken | took 59183ms 02:19:22 INFO - SUITE-END | took 1173s 02:19:22 INFO - Completed test suite (00:19:33) 02:19:22 INFO - javascript benchmark 02:19:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.5628412022315, 3424.6061702904167, 3425.8458369373375, 3431.210821511054, 3469.6183419823815, 5660.575456840738, 5669.886602267952, 5670.121131224667, 5677.829665110043, 5687.829365119042, 5663.9433605664035, 5694.345150307716, 5695.45893140152, 5696.715164241792, 5699.772009119641, 5078.958813443235, 5119.001794650045, 5143.177091665334, 5162.277281180638, 5184.637075404723, 5204.739763011853, 5211.86970325743, 5217.660852044605, 5219.530242278194, 5226.529612334889, 5111.054454925832, 5156.639035267533, 5179.378474583055, 5192.273081768537, 5202.869928251786, 5190.948090519103, 5207.270982062514, 5209.0363282792805, 5235.345581802275, 5245.86885327866, 4316.525182229952, 5013.974930125362, 5041.546260836542, 5121.539061484485, 5142.562882155022, 5521.365043020024, 5529.502344788948, 5548.528375088123, 5588.804391846306, 5654.717264136777, 6180.505559555225, 6208.0, 6210.441060304594, 6222.844428889314, 6226.221722284715, 6146.938530614681, 6164.6917654117115, 6232.875342493125, 6266.686665666698, 6286.025666021774, 5781.6241944274325, 5795.88408231833, 5796.101604251347, 5797.681127537984, 5838.649681019195, 6307.400796924285, 6312.021636646327, 6313.40022697843, 6313.684315784192, 6319.905201421982, 5158.613104017214, 6019.759209631653, 6104.481119104837, 6163.19878415812, 6168.691565421711, 6057.909131363033, 6063.514918806485, 6064.120702498113, 6080.817575472743, 6141.96929015361, 5969.701514924323, 5981.700914954235, 5984.222051133324, 6011.188489553854, 6020.458158765732, 5997.370276121032, 6007.849803754941, 6153.876922461525, 6154.476869466056, 6154.907676384858, 4901.730404827768, 4962.429320628174, 4972.8508144755715, 4989.301497790312, 5007.123753343223, 4345.369921361448, 4395.27477966132, 4402.779861006937, 4409.140217657526, 4444.511103778582, 5525.977694126599, 5638.154276858504, 5667.914981275284, 5679.4320567943705, 5696.11710184922, 6175.382461753788, 6278.372162783777, 6298.433141017239, 6303.684815759194, 6313.873722525524, 5643.101465294475, 5660.122680984454, 5665.093585026376, 5669.943300566942, 5671.092625179951, 5245.370555533361, 5332.893342133136, 5340.8664783379945, 5390.811321603725, 5392.191171324333, 6335.524835637346, 6409.7436102555375, 6411.487081033506, 6417.358264173732, 6430.2605200400685, 6263.185785847811, 6264.0, 6309.652969086745, 6313.116163737172, 6319.431251187415, 6236.251649802053, 6255.687215639291, 6390.073439351175, 6406.359364063556, 6411.743530258737, 5979.970100149471, 6016.127661489147, 6025.758969641165, 6068.150458935665, 6068.332483426731, 5286.471352864683, 6164.938350616437, 6173.166622505904, 6176.9073463897175, 6181.721822518088, 5882.558808089496, 5894.2926848778425, 5939.821805345847, 6042.577019608585, 6140.815775526741, 6124.418180272867, 6127.6936153193055, 6134.263888333428, 6141.232345956755, 6148.538859585638, 5912.615679980788, 5971.910421343597, 5978.581499295182, 5979.910301345571, 5987.101934709828, 5899.557533185114, 5916.911246331395, 6101.420365065312, 6104.786332478342, 6120.693965301806, 5908.881822363614, 5938.198343223609, 5941.970290148522, 5962.1951036611235, 5964.7912323068485], "value": 5656.17723292327, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.0991260774303, 507.43674521281383, 514.3801718928692, 521.1349160393747, 560.924275222845, 29180.270493237655, 36399.0, 40600.79699601498, 40768.59231407685, 40790.38814417782, 42584.0, 43142.784286078524, 43239.27042918287, 43249.56750432495, 43496.91257718558, 8613.526256055915, 8814.118588141198, 9660.903390966107, 9874.456904870229, 9875.95062024689, 11503.0, 11698.532058717683, 11729.589464368748, 11730.7653846923, 11778.941105294462, 11749.471273792657, 11940.641780746548, 11954.282743035446, 11955.222910510789, 11957.521699132069, 8643.481391116522, 11599.826002609883, 11695.415229238504, 11836.46735896878, 11848.992835608897, 11637.35994520301, 11800.114991375682, 11804.291742495478, 11806.822897656542, 11833.881661183364, 41328.173436531404, 42029.57970420287, 42080.579194207974, 42089.579104208875, 42171.578284217074, 2883.9711602883913, 3195.6005499312587, 3238.2875767331147, 3289.3750187464307, 3392.694657480814, 27674.0, 28057.0, 28401.71598284032, 29217.853910730522, 30585.847070764725, 2706.2287248134385, 2706.6075419064323, 2737.931551711223, 2795.9720402795915, 2801.80387372884, 322797.0, 390669.0, 391592.04203979083, 392383.0, 393536.0, 62950.68524657439, 64207.67896160489, 64302.67848660727, 64317.67841160858, 64571.03143452758, 64708.0, 66076.00885986714, 66098.6780264392, 66123.33876661172, 66141.0, 16980.0, 17220.31118755261, 17241.65516689659, 17243.3964811231, 17244.827551724575, 11824.467898944567, 11988.400579971141, 12779.872201277869, 12977.35113244334, 13022.869771302356, 17065.7440138398, 20412.285570004977, 20521.692174617394, 20603.0, 20613.58772824565, 7348.96325518369, 8228.794280143044, 8341.5412152331, 8479.491230526188, 8483.182567844371, 926.9953650231706, 948.8671585977969, 1011.3729487717662, 1068.1881769854965, 1083.8916108389255, 30178.09465716032, 30427.847860760554, 30789.0, 31007.534886976715, 31013.51891848647, 770.6416516319827, 1138.7950168969498, 1169.3977601535216, 1207.782599132147, 1217.8843009914044, 13442.46230150783, 15141.924290378478, 15233.847661523243, 15453.0, 15511.922440387725, 483666.0, 487742.0, 491303.0, 491948.0, 492100.0, 62697.68651156715, 63320.0, 63320.68339658364, 63460.0, 63490.0, 1177.4466000979526, 1305.4386613755987, 1485.5097817720343, 1508.351408894186, 1528.205333226738, 29490.705092948796, 29526.704732953254, 29806.552901706058, 30254.243643909078, 30319.69680303257, 49952.75023624858, 50416.24375634442, 50442.2433663488, 50473.0, 50486.46594738333, 354328.0, 358359.0, 358983.0, 359203.0, 360122.0, 26710.465790683687, 28532.714672853836, 28540.143795686163, 28547.0, 28580.571291430228, 44184.779076104416, 45174.09651807011, 45792.77103614461, 45839.31241031321, 46421.83945401257, 36847.815760921025, 37411.0, 37654.0, 37916.43125353177, 38794.80602596969, 36436.27127457489, 37100.44349334816, 37443.43834842425, 37728.245435091696, 37790.811045944596, 36431.0, 36449.635503644626, 37113.814430927676, 37152.628473715995, 37721.24557508889, 32972.1756956078, 34674.47988280127, 34680.826595866856, 34710.47934281038, 34847.47728784121, 25374.492510150063, 25468.33295916571, 25552.488950221265, 25625.10312139066, 26043.088491902694], "value": 19923.291288031123, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.44235502019257, 407.4967415242173, 407.9041425265063, 409.8278722936368, 416.68332067628614, 14890.329935152917, 20835.791642083575, 21238.0, 21596.460088497777, 21621.891890540544, 15868.523944281662, 18546.350877719284, 20842.270520531783, 20930.476738081554, 21366.35900922971, 5919.112133180014, 6128.019516877278, 6138.324784273726, 6142.692865356737, 6150.0159974404105, 5927.347991720907, 6006.789762358297, 6015.218021657179, 6025.096235564656, 6076.787312444066, 5799.65202087874, 5915.497182739472, 5934.198883150784, 5937.643741375509, 5940.851478713023, 5840.299164100293, 5905.586608937376, 5910.468057874769, 5910.527157827385, 5945.732442040118, 5045.293658887758, 5193.376794784613, 5196.012757576049, 5213.608979326527, 5219.19102539107, 25125.497490050097, 26159.869200653873, 26445.735542644714, 27327.86336068307, 27420.90316387342, 2324.360800779782, 2362.2795047510504, 2368.0764501844296, 2392.377981724747, 2683.6242925990377, 14372.35324410404, 15135.924320378437, 15364.615884602863, 15413.691726165414, 15781.28984195714, 1664.1595994022941, 1732.1079643983358, 1735.5834599695982, 1782.3761683410703, 1817.4184261036473, 123128.0, 123476.76523234743, 126114.73885261214, 127454.72545274522, 128662.0, 10755.300905441121, 11156.721081973015, 11374.56392007118, 11415.42922853854, 11467.426628668532, 14538.27308634564, 14622.415103395957, 14650.120992740469, 14651.267436628126, 14683.55949321522, 6771.0, 7825.843483130306, 8580.442271252474, 8827.602757875911, 9007.414518056305, 7335.339819416278, 8991.505467199237, 8992.46045237288, 9018.729438116867, 9063.81872362549, 6737.595744255263, 6847.432212920155, 6873.896891546631, 6880.69036893341, 6918.757843475454, 1886.3114963038392, 1905.694599199525, 1922.557811703288, 1922.6346994071082, 1929.8938558379143, 920.30516959695, 932.6922115701802, 963.210167662524, 1013.964511242103, 1084.436093231531, 20071.899640501702, 21254.545274400643, 22215.778132202562, 22245.55508889813, 22336.553268934855, 963.6675347005272, 979.1530326267728, 1010.1363334349172, 1017.9083882450615, 1085.6146068145695, 11049.392283424328, 11090.57485963192, 11196.9362910525, 11258.155638327158, 11279.379634120201, 590570.0, 607369.9263007402, 643281.0, 645736.0, 652452.0, 127063.0, 128205.71794282124, 131276.0308595372, 133723.0, 135704.0, 1068.5191663751343, 1075.3279200499687, 1098.7253186703324, 1100.8568886044764, 1103.9944800275948, 18110.81889181091, 18613.255469781056, 18784.343056594367, 18893.055347232857, 19191.80808191956, 48934.75532622314, 50523.49476505188, 50899.236511451614, 51509.0, 53853.46146538641, 76127.0, 77240.6137969329, 78049.0487737811, 78694.0, 80525.19474805411, 8173.5504547250475, 8285.4200205985, 8297.464968980757, 8515.574221289035, 8533.74398768013, 10955.34267943926, 11929.701757456132, 12068.155229134227, 13014.544490942772, 13158.078934474495, 10935.507902144263, 11225.214235003472, 11227.96844851869, 11247.931446512568, 11248.662540123809, 9138.908610913806, 10757.246992710436, 10897.29167604135, 10960.945195273973, 11067.391293478937, 10482.689663792025, 10597.152227821602, 11239.66281011571, 11299.887001130212, 11863.822042669539, 8150.918490815253, 8158.184181581794, 8192.385571082074, 8472.0, 8494.150584941457, 8505.0, 10665.89334106649, 11023.503942322453, 11280.717982050515, 11548.826767598324], "value": 9879.734184624249, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.7081612861298, 1578.810542734872, 1615.2811998660595, 1778.3864566724476, 1903.6954087346019, 7934.44458887878, 8835.0, 9015.729528114167, 9018.0, 9019.594118264677, 8568.957155214232, 8833.955830220855, 8845.513496757676, 8847.071057538962, 8848.380613357067, 2434.196715084027, 2459.348272707734, 2463.950720985579, 2464.0759715106833, 2471.8146139039554, 2409.132712223598, 2462.052109937669, 2463.6674049003336, 2465.617829236471, 2469.074097213545, 2417.1419146203107, 2439.841410308324, 2454.6808914841135, 2458.913938012161, 2462.261321603512, 2404.1825779235055, 2427.356750461129, 2433.3551608823677, 2438.756124387565, 2440.2069327468553, 2369.312899259213, 2403.735589085199, 2404.326788499223, 2407.3981504623844, 2407.650890620868, 7517.586532740697, 8740.51927144007, 8832.02847686754, 8836.734897953073, 8839.823203535958, 5264.184051472028, 5440.591955603346, 5479.260299859548, 5489.945100548984, 5500.972495137538, 3427.2117412994903, 3506.6142724300307, 3582.3551760683067, 3584.9283014339826, 3593.4969104325414, 1032.550840384435, 1039.5270152080784, 1042.7705904700954, 1045.6915210013065, 1060.083028180626, 11745.765084698258, 11842.703932401673, 11878.584249551224, 11888.465019074163, 11910.285382877055, 4556.2937744649635, 4616.146012987608, 4687.695299805502, 4709.905801884011, 4716.174669432836, 9307.395019323721, 9311.90688093124, 9312.813743725088, 9313.20837728787, 9330.300227482965, 5216.4522725114075, 5299.5760339172775, 5311.123664595299, 5332.440093790096, 5353.2773075634295, 4429.2027435062, 4485.259932111165, 4651.441826980784, 4938.851834444972, 4973.925391119136, 3228.3059142284524, 3330.7501937354796, 3856.672182864488, 3898.6101389861356, 3929.4302326162547, 1042.8748550174028, 1199.2264989082169, 1209.0629761934572, 1215.9025680700897, 1218.3786269002871, 859.1236938323001, 889.4707648948994, 901.7520181950016, 910.6721580231111, 928.7445952363154, 5936.22829032223, 6057.091436284504, 6115.663638499836, 6192.690365481708, 6238.906416403757, 906.5059542549195, 928.2620316848122, 934.6915517879083, 962.3936919740659, 974.9268804839666, 3423.845926933294, 3993.2412841560013, 4008.8396464141106, 4058.2695114879607, 4115.650169735606, 10429.478526073666, 10864.565417383215, 10909.0, 11010.944945275221, 11030.172737044753, 10841.83737243942, 10854.67435976922, 10864.239503234698, 10870.891291087146, 10987.340759554452, 7945.284924356835, 8114.066882308492, 8313.210245026712, 8337.833243335099, 8411.747647570583, 5916.645001299936, 5934.673592952429, 6005.339412664516, 6099.9390006099375, 6138.662373569497, 9712.125908668253, 10097.394156350643, 10198.439085850052, 10209.693709188736, 10217.795644086927, 9636.662716804878, 9949.651762188289, 10065.496725163566, 10226.437545934747, 10354.637587684696, 6713.1944166700305, 7430.628468576658, 7530.6611202497115, 7587.810304742426, 7674.424418168548, 8153.6330865112395, 8257.298129658806, 8349.707760228363, 8360.749177524684, 8366.790830229293, 7460.2912723293375, 7467.813304667427, 7515.323620874147, 7597.392208623407, 7616.23837616234, 7314.305141011595, 7423.443241756998, 7458.925410745823, 7513.511621744569, 7559.0, 7021.473389495911, 7027.086478757728, 7081.0, 7102.360787529147, 7115.181754098345, 4547.022390185994, 4902.7058376497525, 4942.703437793743, 4983.07813054586, 5171.146760784429, 5035.546800787946, 5063.063333283354, 5072.644914856072, 5142.357205349332, 5272.209168624584], "value": 4606.3444490636275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.131426605436, 314.43401876622085, 319.16856588586717, 322.9208843833261, 325.95762550868386, 4642.466116396616, 5280.762365693543, 5314.920276195855, 5315.840524784253, 5317.202419637057, 4091.4067460218253, 4358.673099517541, 4390.956090439095, 4483.439570053743, 4630.907381852361, 3810.5427348718194, 3879.6809084911124, 3916.5300163980364, 3975.085730282037, 4011.077452185999, 3533.628968958261, 3842.1931394407206, 4067.2882245607047, 4068.5524592294823, 4070.2470043041985, 3783.4892289540835, 4006.6794656427414, 4015.1969606078756, 4021.3163762160357, 4023.2154729827694, 3861.655637407916, 3904.9023774405723, 4061.228366610364, 4061.6547593454593, 4076.2051399977017, 2984.552666813214, 3215.5176723491663, 3565.6434356564396, 3573.6605022522817, 3587.1390866192187, 5025.673331233458, 5061.696298222082, 5067.265246539268, 5093.617978651616, 5135.948640513584, 4289.742615443083, 4530.705504142253, 4616.561426664462, 4642.303654451859, 4665.743384113873, 4569.337446070334, 4647.55848194421, 4742.95257047432, 5299.8410047698635, 5528.889422211573, 3351.927383237365, 3658.615845336228, 3767.6609105180664, 3924.548676902164, 3960.2277555876617, 4905.558499735041, 5233.816816411408, 5769.576777753354, 5795.507381872546, 5856.531477481792, 3364.7644664873715, 3958.44581758554, 3985.442038114666, 3992.181602771425, 4017.6986725995084, 3417.7607567470536, 3586.372384832644, 4062.2281766464275, 4065.5324637666454, 4068.5524592294823, 4477.664175186809, 4625.190591646448, 4642.16441040616, 4653.8132776141865, 4661.650376221797, 4990.251462280615, 5478.479544443351, 5541.639793413416, 5550.972245138749, 5574.554035677136, 1230.3110258255406, 1313.5139998200727, 1438.8848892088608, 1454.7672372420573, 1461.189040082743, 794.940379471542, 864.7492227254025, 961.5336561767538, 988.7182153086336, 991.8016396720541, 4370.0, 4693.310408253026, 5536.4186760390385, 5555.222388805597, 5580.581456390787, 5656.688882111441, 6173.351798061229, 6194.349593292639, 6194.946859033975, 6237.4698150657705, 4733.2900064989835, 5511.614187006951, 5578.44215578447, 5579.995600791896, 5602.915956260578, 2758.3379988802544, 3185.9804862444257, 3197.928693887574, 3233.692799184074, 3241.9837900810344, 5905.734241959122, 6118.388161183935, 6405.487560995202, 6415.775447859396, 6428.260750013715, 6248.218972628421, 6268.811935641938, 6275.6862156892885, 6287.874242515124, 6291.465225455796, 5091.541761241506, 5307.7080760557765, 6397.96801015992, 6399.472208477625, 6406.263279722705, 5930.881382372242, 6087.57386982907, 6109.800471830049, 6127.234095738033, 6152.507799376129, 5216.452272511332, 5334.359876814807, 5533.889322213453, 6026.216591843032, 6104.938950610437, 4981.850544483671, 5169.715665638426, 6361.173047503795, 6414.711337989708, 6419.4864410846085, 3558.626344233818, 4192.568310380033, 4212.326027835532, 4234.470691163605, 4265.87202383929, 5043.394792624908, 5841.6856207353, 5909.674967876808, 6114.2357205349335, 6145.569810113428, 5115.360579927509, 5174.818881339135, 5783.0, 5912.940870591239, 5925.674087925206, 5193.76628051731, 5380.488853558905, 5802.883942321215, 5865.354810970875, 5886.293644762656, 3919.3141200289265, 5337.919931201112, 5739.368669446274, 5794.188813566188, 5895.145203945318, 4645.49021567985, 5155.845324640267, 5317.8462722597405, 5544.1960915666195, 5562.638428502134, 5040.7479626019285, 5405.648632838853, 5425.996190704732, 5452.454754524516, 5458.454154584669], "value": 4154.420122660192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.5730561663006, 725.4667819152925, 732.2750477027743, 764.5871229536051, 773.3581127664039, 14413.05438166984, 14540.563783086498, 15371.615709607266, 15383.077015379085, 15385.69228615427, 13935.930320348409, 14040.859591404083, 14070.94467914908, 14095.57713268601, 14106.647333816634, 735.2537174767615, 915.624593916496, 934.042606328512, 935.0836180543072, 935.6771913689767, 746.6192241956587, 899.1503029636996, 927.0821886332516, 927.1238679447924, 927.8840144981878, 956.8277710012195, 958.3674774648733, 966.830804609194, 967.7822489939778, 968.6222373274429, 763.6868883757633, 959.1176117971482, 961.1637875048717, 961.995190024049, 962.2398305338774, 572.1846368924266, 581.2647001543052, 581.3750218515113, 705.0869124483805, 723.1972510513326, 14794.704105917928, 14831.406743730238, 14839.406423743038, 14843.703125937422, 14861.405543778235, 12309.322987235699, 12422.378881056002, 12627.873721262762, 12664.81002784959, 12740.872591274061, 9757.853632195523, 9914.305998580103, 10023.649172279009, 10039.74900627483, 10684.19868509857, 3430.8456119474686, 3453.4819777033395, 3480.738944579167, 3483.8432270547887, 3565.8217089145437, 76935.0, 77622.22377776206, 79203.20796792016, 79349.80975285375, 79959.60020199862, 9528.952355238318, 9648.71053868385, 9808.705738827846, 9821.36161149523, 9853.0, 22082.22712205065, 22104.778952210592, 22133.557328853334, 22147.0, 22150.33548993533, 6825.112735344473, 7255.129384473791, 7389.113306403158, 7405.555666660018, 7609.923900761032, 3999.0, 4081.89795255121, 4175.164967006611, 4195.727277726938, 4357.411749413852, 3002.9849850750606, 3606.0, 3614.0784100054843, 3616.457531370263, 3622.003948914017, 623.8066199478086, 645.3772109913956, 645.8934275844528, 651.8989556618732, 664.3655309179721, 495.66294919454396, 561.943805619443, 587.4301927130676, 588.9469947704728, 591.7987884119334, 7946.688796348653, 8172.264496195371, 8182.549959752271, 8212.297476096539, 8276.213759692819, 570.5293133165155, 579.8724280658248, 598.1625723986367, 598.7844376024627, 602.8040886711749, 3794.962050379516, 3855.479510266134, 3856.9421458678144, 3942.7240093193773, 3953.8418463261146, 97555.51222243985, 98305.5084724586, 98985.01014989903, 100588.0, 101017.47456313504, 75909.48181036349, 77117.45765084779, 78343.0, 79539.0, 84107.0, 72370.1907452318, 72993.6350318245, 73044.90432643409, 73052.63473682704, 73120.0, 7186.676599553137, 7471.92528074734, 7665.770026899202, 7761.417893658111, 7931.881021784793, 42713.718588442396, 43479.9130021752, 43807.78096109499, 44374.778126109166, 44520.999999998705, 61114.38885611087, 63963.68018159879, 64207.71584568376, 64522.0, 64833.0, 10516.474176291307, 10556.0, 10645.89354106449, 10703.892961070602, 10727.088197503299, 10800.513976870903, 10965.50655220533, 11037.172212083966, 11067.778644427228, 11709.180357374902, 9987.350822196535, 10227.48862556884, 10274.33216840903, 10531.315464494783, 10648.787024259316, 10271.94864025675, 10306.12397946183, 10533.36799792015, 10626.309289896131, 10646.73383165427, 9980.101790838859, 10028.749281268027, 10175.28772985884, 10329.9483502582, 10595.993380628828, 8893.555322233991, 9111.453312801254, 9210.217131543894, 9316.114969077928, 9713.854292185764, 8971.237444817263, 9360.812783744152, 9426.293028023063, 9484.0, 9572.186364158846], "value": 6763.90175880043, "unit": "score"}], "name": "dromaeo_css", "value": 7240.855133445431, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 108.0, 112.0, 115.0, 114.0, 114.0, 112.0, 104.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 160.0, 177.0, 141.0, 151.0, 137.0, 124.0, 191.0, 142.0], "value": 149.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 189.0, 183.0, 186.0, 183.0, 184.0, 184.0, 186.0, 184.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 72.0, 152.0, 56.0, 55.0, 56.0, 69.0, 129.0, 56.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 148.0, 76.0, 82.0, 85.0, 114.0, 108.0, 107.0, 146.0], "value": 104.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 89.0, 86.0, 89.0, 84.0, 126.0, 85.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 146.0, 146.0, 95.0, 95.0, 100.0, 94.0, 95.0, 94.0, 94.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 94.0, 137.0, 128.0, 86.0, 85.0, 92.0, 135.0], "value": 101.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 54.0, 88.0, 91.0, 90.0, 64.0, 64.0, 86.0, 60.0, 59.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 71.0, 73.0, 75.0, 71.0, 68.0, 67.0, 69.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 80.0, 81.0, 80.0, 80.0, 81.0, 82.0, 86.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 125.0, 151.0, 143.0, 146.0, 131.0, 136.0, 175.0, 135.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 176.0, 149.0, 189.0, 214.0, 180.0, 167.0, 166.0, 167.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 69.0, 70.0, 68.0, 66.0, 69.0, 70.0, 68.0, 66.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1537.6000000000001, "alertThreshold": 2.0}]} 02:19:22 INFO - Return code: 0 02:19:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:19:23 ERROR - # TBPL SUCCESS # 02:19:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:19:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:19:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:19:23 INFO - 2016-05-01 02:19:23.050 screenresolution[1964:26795] starting screenresolution argv=screenresolution get 02:19:23 INFO - 2016-05-01 02:19:23.053 screenresolution[1964:26795] Display 0: 1600x1200x32@60 02:19:23 INFO - 2016-05-01 02:19:23.058 screenresolution[1965:26798] starting screenresolution argv=screenresolution list 02:19:23 INFO - Available Modes on Display 0 02:19:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:19:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:19:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:19:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:19:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:19:23 INFO - Intel Iris: 02:19:23 INFO - Chipset Model: Intel Iris 02:19:23 INFO - Type: GPU 02:19:23 INFO - Bus: Built-In 02:19:23 INFO - VRAM (Dynamic, Max): 1536 MB 02:19:23 INFO - Vendor: Intel (0x8086) 02:19:23 INFO - Device ID: 0x0a2e 02:19:23 INFO - Revision ID: 0x0009 02:19:23 INFO - Displays: 02:19:23 INFO - Display: 02:19:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:19:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:19:23 INFO - Display Serial Number: 1600x1200 60 02:19:23 INFO - Main Display: Yes 02:19:23 INFO - Mirror: Off 02:19:23 INFO - Online: Yes 02:19:23 INFO - Rotation: Supported 02:19:23 INFO - Return code: 0 02:19:23 INFO - Running post-action listener: _resource_record_post_action 02:19:23 INFO - Running post-run listener: _resource_record_post_run 02:19:24 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 675840; Write bytes: 652888064; Read time: 8; Write time: 1954 02:19:24 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354167296; Write bytes: 355735552; Read time: 27809; Write time: 703 02:19:24 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 675840; Write bytes: 296144896; Read time: 8; Write time: 1246 02:19:24 INFO - Running post-run listener: _upload_blobber_files 02:19:24 INFO - Blob upload gear active. 02:19:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:19:24 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 02:19:24 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:19:24 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:19:24 INFO - (blobuploader) - INFO - Open directory for files ... 02:19:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:19:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:19:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:25 INFO - (blobuploader) - INFO - Done attempting. 02:19:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:19:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:19:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:26 INFO - (blobuploader) - INFO - Done attempting. 02:19:26 INFO - (blobuploader) - INFO - Iteration through files over. 02:19:26 INFO - Return code: 0 02:19:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:19:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:19:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21452258a7097000f68e575a0900e021feba807121fd739b5a2feef377e45aacbdeac929c1e4817c15e5e533af7c9bfa49e2189e1a5e2adf6782b500ec9c99db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7839ae500802f48e0999c5a47142d8e30a39861a12589a1334290b9c89e50c23711ab65cf61a696a9ce6098f1e0e144ba9682fda1e5969a5ff6667fb9d13a20"} 02:19:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:19:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:19:26 INFO - Contents: 02:19:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21452258a7097000f68e575a0900e021feba807121fd739b5a2feef377e45aacbdeac929c1e4817c15e5e533af7c9bfa49e2189e1a5e2adf6782b500ec9c99db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7839ae500802f48e0999c5a47142d8e30a39861a12589a1334290b9c89e50c23711ab65cf61a696a9ce6098f1e0e144ba9682fda1e5969a5ff6667fb9d13a20"} 02:19:26 INFO - Running post-run listener: copy_logs_to_upload_dir 02:19:26 INFO - Copying logs to upload dir... 02:19:26 INFO - mkdir: /builds/slave/test/build/upload/logs 02:19:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1414.883917 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 35 secs) (at 2016-05-01 02:19:27.295447) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-01 02:19:27.296621) ========= 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.5fY73WUSNq/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.408s8mflgi/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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/Mozilla-Aurora/sha512/21452258a7097000f68e575a0900e021feba807121fd739b5a2feef377e45aacbdeac929c1e4817c15e5e533af7c9bfa49e2189e1a5e2adf6782b500ec9c99db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7839ae500802f48e0999c5a47142d8e30a39861a12589a1334290b9c89e50c23711ab65cf61a696a9ce6098f1e0e144ba9682fda1e5969a5ff6667fb9d13a20"} build_url:https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011127 build_url: 'https://queue.taskcluster.net/v1/task/CFV2EmqmTPCsm1OMkk7Ipw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/21452258a7097000f68e575a0900e021feba807121fd739b5a2feef377e45aacbdeac929c1e4817c15e5e533af7c9bfa49e2189e1a5e2adf6782b500ec9c99db", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7839ae500802f48e0999c5a47142d8e30a39861a12589a1334290b9c89e50c23711ab65cf61a696a9ce6098f1e0e144ba9682fda1e5969a5ff6667fb9d13a20"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-01 02:19:27.371929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-01 02:19:27.372302) ========= 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.5fY73WUSNq/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.408s8mflgi/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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.005244 ========= master_lag: 5.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-05-01 02:19:33.040564) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-01 02:19:33.044706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-01 02:19:33.877301) ========= ========= Total master_lag: 6.36 =========