builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0150 starttime: 1484051218.11 results: success (0) buildid: 20170110030221 builduid: 5e8c5c5d2f284239ae48b38251ffd097 revision: 8f3b24109e3412b36f97277e31ad66856dc609d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.114824) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.115261) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.115531) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.142331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.142712) ========= 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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 --2017-01-10 04:26:58-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2017-01-10 04:26:58 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214349 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.381057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.381364) ========= 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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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.026687 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:26:58.441989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-10 04:26:58.442288) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8f3b24109e3412b36f97277e31ad66856dc609d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8f3b24109e3412b36f97277e31ad66856dc609d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fmx333y9o3/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.zoP3Ycc2Hb/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 2017-01-10 04:26:58,540 truncating revision to first 12 chars 2017-01-10 04:26:58,540 Setting DEBUG logging. 2017-01-10 04:26:58,540 attempt 1/10 2017-01-10 04:26:58,540 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8f3b24109e34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 04:27:00,588 unpacking tar archive at: mozilla-central-8f3b24109e34/testing/mozharness/ program finished with exit code 0 elapsedTime=2.334833 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-10 04:27:00.852140) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 04:27:00.852439) ========= script_repo_revision: 8f3b24109e3412b36f97277e31ad66856dc609d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 04:27:00.856063) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 04:27:00.856344) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 04:27:00.944512) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-10 04:27:00.944896) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fmx333y9o3/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.zoP3Ycc2Hb/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:27:01 INFO - MultiFileLogger online at 20170110 04:27:01 in /builds/slave/test 04:27:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:27:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:27:01 INFO - {'append_to_log': False, 04:27:01 INFO - 'base_work_dir': '/builds/slave/test', 04:27:01 INFO - 'blob_upload_branch': 'Firefox', 04:27:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:27:01 INFO - 'branch': 'Firefox', 04:27:01 INFO - 'buildbot_json_path': 'buildprops.json', 04:27:01 INFO - 'config_files': ('talos/mac_config.py',), 04:27:01 INFO - 'default_actions': ('clobber', 04:27:01 INFO - 'read-buildbot-config', 04:27:01 INFO - 'download-and-extract', 04:27:01 INFO - 'populate-webroot', 04:27:01 INFO - 'create-virtualenv', 04:27:01 INFO - 'install', 04:27:01 INFO - 'run-tests'), 04:27:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:27:01 INFO - 'download_minidump_stackwalk': True, 04:27:01 INFO - 'download_symbols': 'ondemand', 04:27:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:27:01 INFO - 'tooltool.py': '/tools/tooltool.py', 04:27:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:27:01 INFO - '/tools/misc-python/virtualenv.py')}, 04:27:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:27:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:27:01 INFO - 'installer_path': 'installer.exe', 04:27:01 INFO - 'log_level': 'info', 04:27:01 INFO - 'log_name': 'talos', 04:27:01 INFO - 'log_to_console': True, 04:27:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:27:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:27:01 INFO - 'opt_config_files': (), 04:27:01 INFO - 'pip_index': False, 04:27:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:27:01 INFO - 'cmd': ('bash', 04:27:01 INFO - '-c', 04:27:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:27:01 INFO - 'enabled': True, 04:27:01 INFO - 'halt_on_failure': False, 04:27:01 INFO - 'name': 'check_screen_resolution'},), 04:27:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:27:01 INFO - 'cmd': ('bash', 04:27:01 INFO - '-c', 04:27:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:27:01 INFO - 'enabled': True, 04:27:01 INFO - 'halt_on_failure': False, 04:27:01 INFO - 'name': 'check_screen_resolution'},), 04:27:01 INFO - 'run_cmd_checks_enabled': True, 04:27:01 INFO - 'sps_profile': False, 04:27:01 INFO - 'sps_profile_interval': 0, 04:27:01 INFO - 'suite': 'dromaeojs', 04:27:01 INFO - 'system_bits': '32', 04:27:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:27:01 INFO - 'title': 't-yosemite-r7-0150', 04:27:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:27:01 INFO - 'use_talos_json': True, 04:27:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:27:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:27:01 INFO - 'work_dir': 'build'} 04:27:01 INFO - [mozharness: 2017-01-10 12:27:01.147255Z] Running clobber step. 04:27:01 INFO - Running pre-action listener: _resource_record_pre_action 04:27:01 INFO - Running main action method: clobber 04:27:01 INFO - rmtree: /builds/slave/test/build 04:27:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:27:03 INFO - Running post-action listener: _resource_record_post_action 04:27:03 INFO - [mozharness: 2017-01-10 12:27:03.506000Z] Finished clobber step (success) 04:27:03 INFO - [mozharness: 2017-01-10 12:27:03.506131Z] Running read-buildbot-config step. 04:27:03 INFO - Running pre-action listener: _resource_record_pre_action 04:27:03 INFO - Running main action method: read_buildbot_config 04:27:03 INFO - Using buildbot properties: 04:27:03 INFO - { 04:27:03 INFO - "project": "", 04:27:03 INFO - "product": "firefox", 04:27:03 INFO - "script_repo_revision": "production", 04:27:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:27:03 INFO - "repository": "", 04:27:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:27:03 INFO - "buildid": "20170110030221", 04:27:03 INFO - "stage_platform": "macosx64", 04:27:03 INFO - "basedir": "/builds/slave/test", 04:27:03 INFO - "nightly_build": "True", 04:27:03 INFO - "buildnumber": 72, 04:27:03 INFO - "slavename": "t-yosemite-r7-0150", 04:27:03 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:27:03 INFO - "platform": "yosemite_r7", 04:27:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:27:03 INFO - "branch": "mozilla-central", 04:27:03 INFO - "repo_path": "mozilla-central", 04:27:03 INFO - "revision": "8f3b24109e3412b36f97277e31ad66856dc609d6", 04:27:03 INFO - "pgo_build": "False", 04:27:03 INFO - "builduid": "5e8c5c5d2f284239ae48b38251ffd097", 04:27:03 INFO - "slavebuilddir": "test" 04:27:03 INFO - } 04:27:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:27:03 INFO - Running post-action listener: _resource_record_post_action 04:27:03 INFO - [mozharness: 2017-01-10 12:27:03.509645Z] Finished read-buildbot-config step (success) 04:27:03 INFO - [mozharness: 2017-01-10 12:27:03.509750Z] Running download-and-extract step. 04:27:03 INFO - Running pre-action listener: _resource_record_pre_action 04:27:03 INFO - Running main action method: download_and_extract 04:27:03 INFO - mkdir: /builds/slave/test/build/tests 04:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:03 INFO - trying https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:27:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:27:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:27:04 INFO - Downloaded 1199 bytes. 04:27:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:27:04 INFO - Using the following test package requirements: 04:27:04 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:27:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:27:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:27:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'jsshell-mac.zip'], 04:27:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:27:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:27:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:27:04 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:27:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:27:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:27:04 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:27:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:27:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:27:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:27:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:27:05 INFO - Expected file size: 16306283 04:27:05 INFO - Obtained file size: 16306283 04:27:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:27:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:27:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:27:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:27:12 INFO - Expected file size: 16306283 04:27:12 INFO - Obtained file size: 16306283 04:27:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:27:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:27:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:27:19 INFO - Expected file size: 14403537 04:27:19 INFO - Obtained file size: 14403537 04:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:20 INFO - trying https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:27:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:27:22 INFO - Downloaded 69843522 bytes. 04:27:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:27:22 INFO - mkdir: /builds/slave/test/properties 04:27:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:27:22 INFO - Writing to file /builds/slave/test/properties/build_url 04:27:22 INFO - Contents: 04:27:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:27:23 INFO - Running post-action listener: _resource_record_post_action 04:27:23 INFO - Running post-action listener: set_extra_try_arguments 04:27:23 INFO - [mozharness: 2017-01-10 12:27:23.736498Z] Finished download-and-extract step (success) 04:27:23 INFO - [mozharness: 2017-01-10 12:27:23.736638Z] Running populate-webroot step. 04:27:23 INFO - Running pre-action listener: _resource_record_pre_action 04:27:23 INFO - Running main action method: populate_webroot 04:27:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:27:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:27:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:27:23 INFO - '--tppagecycles', 04:27:23 INFO - '7'], 04:27:23 INFO - 'tests': ['tsvgm']}}, 04:27:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:27:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:27:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:27:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:27:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['damp', 'tps']}, 04:27:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'tests': ['damp', 'tps']}, 04:27:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['dromaeo_dom']}, 04:27:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:27:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['basic_compositor_video']}, 04:27:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:27:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['a11yr', 04:27:23 INFO - 'ts_paint', 04:27:23 INFO - 'tpaint', 04:27:23 INFO - 'sessionrestore', 04:27:23 INFO - 'sessionrestore_no_auto_restore', 04:27:23 INFO - 'tabpaint']}, 04:27:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:27:23 INFO - 'ts_paint', 04:27:23 INFO - 'tpaint', 04:27:23 INFO - 'sessionrestore', 04:27:23 INFO - 'sessionrestore_no_auto_restore', 04:27:23 INFO - 'tabpaint']}, 04:27:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:27:23 INFO - 'ts_paint', 04:27:23 INFO - 'tpaint', 04:27:23 INFO - 'sessionrestore', 04:27:23 INFO - 'sessionrestore_no_auto_restore', 04:27:23 INFO - 'tabpaint']}, 04:27:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:27:23 INFO - 'ts_paint', 04:27:23 INFO - 'tpaint', 04:27:23 INFO - 'sessionrestore', 04:27:23 INFO - 'sessionrestore_no_auto_restore', 04:27:23 INFO - 'tabpaint']}, 04:27:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['a11yr', 04:27:23 INFO - 'ts_paint', 04:27:23 INFO - 'tpaint', 04:27:23 INFO - 'sessionrestore', 04:27:23 INFO - 'sessionrestore_no_auto_restore', 04:27:23 INFO - 'tabpaint']}, 04:27:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['a11yr', 04:27:23 INFO - 'ts_paint', 04:27:23 INFO - 'tpaint', 04:27:23 INFO - 'sessionrestore', 04:27:23 INFO - 'sessionrestore_no_auto_restore', 04:27:23 INFO - 'tabpaint']}, 04:27:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['tsvgx', 04:27:23 INFO - 'tsvgr_opacity', 04:27:23 INFO - 'tart', 04:27:23 INFO - 'tscrollx', 04:27:23 INFO - 'cart', 04:27:23 INFO - 'tsvg_static']}, 04:27:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:27:23 INFO - 'tsvgr_opacity', 04:27:23 INFO - 'tart', 04:27:23 INFO - 'tscrollx', 04:27:23 INFO - 'cart', 04:27:23 INFO - 'tsvg_static']}, 04:27:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'talos_options': ['--disable-e10s'], 04:27:23 INFO - 'tests': ['tp5o']}, 04:27:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:27:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'talos_options': ['--disable-e10s', 04:27:23 INFO - '--xperf_path', 04:27:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:23 INFO - 'tests': ['tp5n']}, 04:27:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:27:23 INFO - 'talos_options': ['--xperf_path', 04:27:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:23 INFO - 'tests': ['tp5n']}}, 04:27:23 INFO - 'talos.zip': {'path': '', 04:27:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:27:23 INFO - Running post-action listener: _resource_record_post_action 04:27:23 INFO - [mozharness: 2017-01-10 12:27:23.755138Z] Finished populate-webroot step (success) 04:27:23 INFO - [mozharness: 2017-01-10 12:27:23.755273Z] Running create-virtualenv step. 04:27:23 INFO - Running pre-action listener: _resource_record_pre_action 04:27:23 INFO - Running main action method: create_virtualenv 04:27:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:27:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:27:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:27:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:27:23 INFO - Using real prefix '/tools/python27' 04:27:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:27:24 INFO - Installing distribute.............................................................................................................................................................................................done. 04:27:27 INFO - Installing pip.................done. 04:27:27 INFO - Return code: 0 04:27:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:27:27 INFO - Reading from file tmpfile_stdout 04:27:27 INFO - Output received: 04:27:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:27:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:27 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 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:27 INFO - 'HOME': '/Users/cltbld', 04:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:27 INFO - 'LOGNAME': 'cltbld', 04:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:27 INFO - 'MOZ_NO_REMOTE': '1', 04:27:27 INFO - 'NO_EM_RESTART': '1', 04:27:27 INFO - 'PAGER': '/bin/cat', 04:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:27 INFO - 'PWD': '/builds/slave/test', 04:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:27 INFO - 'SHELL': '/bin/bash', 04:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:27 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:27 INFO - 'USER': 'cltbld', 04:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:27 INFO - 'XPC_FLAGS': '0x0', 04:27:27 INFO - 'XPC_SERVICE_NAME': '0', 04:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:27:27 INFO - Cleaning up... 04:27:28 INFO - Return code: 0 04:27:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:28 INFO - 'HOME': '/Users/cltbld', 04:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:28 INFO - 'LOGNAME': 'cltbld', 04:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:28 INFO - 'MOZ_NO_REMOTE': '1', 04:27:28 INFO - 'NO_EM_RESTART': '1', 04:27:28 INFO - 'PAGER': '/bin/cat', 04:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:28 INFO - 'PWD': '/builds/slave/test', 04:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:28 INFO - 'SHELL': '/bin/bash', 04:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:28 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:28 INFO - 'USER': 'cltbld', 04:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:28 INFO - 'XPC_FLAGS': '0x0', 04:27:28 INFO - 'XPC_SERVICE_NAME': '0', 04:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:28 INFO - Downloading/unpacking psutil>=3.1.1 04:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:27:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:32 INFO - Installing collected packages: psutil 04:27:32 INFO - Running setup.py install for psutil 04:27:32 INFO - building 'psutil._psutil_osx' extension 04:27:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:27:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:27:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:27:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:27:33 INFO - building 'psutil._psutil_posix' extension 04:27:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:27:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:27:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:27:33 INFO - ^ 04:27:33 INFO - 1 warning generated. 04:27:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:27:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:33 INFO - Successfully installed psutil 04:27:33 INFO - Cleaning up... 04:27:33 INFO - Return code: 0 04:27:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:27:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:33 INFO - 'HOME': '/Users/cltbld', 04:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:33 INFO - 'LOGNAME': 'cltbld', 04:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:33 INFO - 'MOZ_NO_REMOTE': '1', 04:27:33 INFO - 'NO_EM_RESTART': '1', 04:27:33 INFO - 'PAGER': '/bin/cat', 04:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:33 INFO - 'PWD': '/builds/slave/test', 04:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:33 INFO - 'SHELL': '/bin/bash', 04:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:33 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:33 INFO - 'USER': 'cltbld', 04:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:33 INFO - 'XPC_FLAGS': '0x0', 04:27:33 INFO - 'XPC_SERVICE_NAME': '0', 04:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:27:36 INFO - Installing collected packages: mozsystemmonitor 04:27:36 INFO - Running setup.py install for mozsystemmonitor 04:27:36 INFO - Successfully installed mozsystemmonitor 04:27:36 INFO - Cleaning up... 04:27:36 INFO - Return code: 0 04:27:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:27:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:36 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:36 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==2.5.1'] in /builds/slave/test/build 04:27:36 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==2.5.1 04:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:36 INFO - 'HOME': '/Users/cltbld', 04:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:36 INFO - 'LOGNAME': 'cltbld', 04:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:36 INFO - 'MOZ_NO_REMOTE': '1', 04:27:36 INFO - 'NO_EM_RESTART': '1', 04:27:36 INFO - 'PAGER': '/bin/cat', 04:27:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:36 INFO - 'PWD': '/builds/slave/test', 04:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:36 INFO - 'SHELL': '/bin/bash', 04:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:36 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:36 INFO - 'USER': 'cltbld', 04:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:36 INFO - 'XPC_FLAGS': '0x0', 04:27:36 INFO - 'XPC_SERVICE_NAME': '0', 04:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:37 INFO - Downloading/unpacking jsonschema==2.5.1 04:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:27:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:27:40 INFO - warning: no files found matching '*.txt' 04:27:40 INFO - no previously-included directories found matching 'build' 04:27:40 INFO - no previously-included directories found matching 'dist' 04:27:40 INFO - no previously-included directories found matching '.git*' 04:27:40 INFO - Installing collected packages: jsonschema, functools32 04:27:40 INFO - Running setup.py install for functools32 04:27:40 INFO - warning: no files found matching '*.txt' 04:27:40 INFO - no previously-included directories found matching 'build' 04:27:40 INFO - no previously-included directories found matching 'dist' 04:27:40 INFO - no previously-included directories found matching '.git*' 04:27:40 INFO - Successfully installed jsonschema functools32 04:27:40 INFO - Cleaning up... 04:27:40 INFO - Return code: 0 04:27:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:27:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:40 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:40 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:27:40 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 functools32==3.2.3-2 04:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:40 INFO - 'HOME': '/Users/cltbld', 04:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:40 INFO - 'LOGNAME': 'cltbld', 04:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:40 INFO - 'MOZ_NO_REMOTE': '1', 04:27:40 INFO - 'NO_EM_RESTART': '1', 04:27:40 INFO - 'PAGER': '/bin/cat', 04:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:40 INFO - 'PWD': '/builds/slave/test', 04:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:40 INFO - 'SHELL': '/bin/bash', 04:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:40 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:40 INFO - 'USER': 'cltbld', 04:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:40 INFO - 'XPC_FLAGS': '0x0', 04:27:40 INFO - 'XPC_SERVICE_NAME': '0', 04:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:27:40 INFO - Cleaning up... 04:27:40 INFO - Return code: 0 04:27:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:27:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:40 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 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:40 INFO - 'HOME': '/Users/cltbld', 04:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:40 INFO - 'LOGNAME': 'cltbld', 04:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:40 INFO - 'MOZ_NO_REMOTE': '1', 04:27:40 INFO - 'NO_EM_RESTART': '1', 04:27:40 INFO - 'PAGER': '/bin/cat', 04:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:40 INFO - 'PWD': '/builds/slave/test', 04:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:40 INFO - 'SHELL': '/bin/bash', 04:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:40 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:40 INFO - 'USER': 'cltbld', 04:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:40 INFO - 'XPC_FLAGS': '0x0', 04:27:40 INFO - 'XPC_SERVICE_NAME': '0', 04:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:40 INFO - Downloading/unpacking blobuploader==1.2.4 04:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:43 INFO - Downloading blobuploader-1.2.4.tar.gz 04:27:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:27:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:27:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:44 INFO - Downloading docopt-0.6.1.tar.gz 04:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:27:44 INFO - Installing collected packages: blobuploader, requests, docopt 04:27:44 INFO - Running setup.py install for blobuploader 04:27:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:27:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:27:45 INFO - Running setup.py install for requests 04:27:45 INFO - Running setup.py install for docopt 04:27:45 INFO - Successfully installed blobuploader requests docopt 04:27:45 INFO - Cleaning up... 04:27:45 INFO - Return code: 0 04:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:45 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 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:45 INFO - 'HOME': '/Users/cltbld', 04:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:45 INFO - 'LOGNAME': 'cltbld', 04:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:45 INFO - 'MOZ_NO_REMOTE': '1', 04:27:45 INFO - 'NO_EM_RESTART': '1', 04:27:45 INFO - 'PAGER': '/bin/cat', 04:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:45 INFO - 'PWD': '/builds/slave/test', 04:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:45 INFO - 'SHELL': '/bin/bash', 04:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:45 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:45 INFO - 'USER': 'cltbld', 04:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:45 INFO - 'XPC_FLAGS': '0x0', 04:27:45 INFO - 'XPC_SERVICE_NAME': '0', 04:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-x36FyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-BBpTDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-nbMTZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-6MnbSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-IjmUPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-bFr5Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-_CRYGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-JNl7ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:46 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-AnH0Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-hiWTWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-YbRqZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-HS6b_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-XNLBp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-W1aOUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-hjZbw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-qGhieQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:47 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-j6wKnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:27:47 INFO - Running setup.py install for manifestparser 04:27:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:27:47 INFO - Running setup.py install for mozcrash 04:27:48 INFO - Running setup.py install for mozdebug 04:27:48 INFO - Running setup.py install for mozdevice 04:27:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:27:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:27:48 INFO - Running setup.py install for mozfile 04:27:48 INFO - Running setup.py install for mozhttpd 04:27:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:27:48 INFO - Running setup.py install for mozinfo 04:27:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:27:48 INFO - Running setup.py install for mozInstall 04:27:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Running setup.py install for mozleak 04:27:49 INFO - Running setup.py install for mozlog 04:27:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Running setup.py install for moznetwork 04:27:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Running setup.py install for mozprocess 04:27:49 INFO - Running setup.py install for mozprofile 04:27:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Running setup.py install for mozrunner 04:27:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:27:49 INFO - Running setup.py install for mozscreenshot 04:27:50 INFO - Running setup.py install for moztest 04:27:50 INFO - Running setup.py install for mozversion 04:27:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:27:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:27:50 INFO - Cleaning up... 04:27:50 INFO - Return code: 0 04:27:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:50 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 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:50 INFO - 'HOME': '/Users/cltbld', 04:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:50 INFO - 'LOGNAME': 'cltbld', 04:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:50 INFO - 'MOZ_NO_REMOTE': '1', 04:27:50 INFO - 'NO_EM_RESTART': '1', 04:27:50 INFO - 'PAGER': '/bin/cat', 04:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:50 INFO - 'PWD': '/builds/slave/test', 04:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:50 INFO - 'SHELL': '/bin/bash', 04:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:50 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:50 INFO - 'USER': 'cltbld', 04:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:50 INFO - 'XPC_FLAGS': '0x0', 04:27:50 INFO - 'XPC_SERVICE_NAME': '0', 04:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-TXkTTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-BcFGPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:27:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-YxWIvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-vm6Etu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-zGpRex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Iv46p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-vCSyvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-of52nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Emga4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-9pi_8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-PvDENU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-mAgVcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:27:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-IsiqLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:52 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-HN0iQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:52 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-7DhWNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:52 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-rD5NNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:52 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-sKkx9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:27:52 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:27:52 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:27:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:55 INFO - Downloading blessings-1.6.tar.gz 04:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:27:55 INFO - Installing collected packages: blessings 04:27:55 INFO - Running setup.py install for blessings 04:27:55 INFO - Successfully installed blessings 04:27:55 INFO - Cleaning up... 04:27:55 INFO - Return code: 0 04:27:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:27:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:27:55 INFO - Reading from file tmpfile_stdout 04:27:55 INFO - Current package versions: 04:27:55 INFO - blessings == 1.6 04:27:55 INFO - blobuploader == 1.2.4 04:27:55 INFO - docopt == 0.6.1 04:27:55 INFO - functools32 == 3.2.3-2 04:27:55 INFO - jsonschema == 2.5.1 04:27:55 INFO - manifestparser == 1.1 04:27:55 INFO - mozInstall == 1.12 04:27:55 INFO - mozcrash == 1.0 04:27:55 INFO - mozdebug == 0.1 04:27:55 INFO - mozdevice == 0.49 04:27:55 INFO - mozfile == 1.2 04:27:55 INFO - mozhttpd == 0.7 04:27:55 INFO - mozinfo == 0.9 04:27:55 INFO - mozleak == 0.1 04:27:55 INFO - mozlog == 3.4 04:27:55 INFO - moznetwork == 0.27 04:27:55 INFO - mozprocess == 0.24 04:27:55 INFO - mozprofile == 0.28 04:27:55 INFO - mozrunner == 6.13 04:27:55 INFO - mozscreenshot == 0.1 04:27:55 INFO - mozsystemmonitor == 0.3 04:27:55 INFO - moztest == 0.8 04:27:55 INFO - mozversion == 1.4 04:27:55 INFO - psutil == 3.1.1 04:27:55 INFO - requests == 1.2.3 04:27:55 INFO - wsgiref == 0.1.2 04:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:55 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 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:55 INFO - 'HOME': '/Users/cltbld', 04:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:55 INFO - 'LOGNAME': 'cltbld', 04:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:55 INFO - 'MOZ_NO_REMOTE': '1', 04:27:55 INFO - 'NO_EM_RESTART': '1', 04:27:55 INFO - 'PAGER': '/bin/cat', 04:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:55 INFO - 'PWD': '/builds/slave/test', 04:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:55 INFO - 'SHELL': '/bin/bash', 04:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:55 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:55 INFO - 'USER': 'cltbld', 04:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:55 INFO - 'XPC_FLAGS': '0x0', 04:27:55 INFO - 'XPC_SERVICE_NAME': '0', 04:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:27:56 INFO - Cleaning up... 04:27:56 INFO - Return code: 0 04:27:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:27:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:56 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 0x109d2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd39bdc43c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, '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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:27:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:56 INFO - 'HOME': '/Users/cltbld', 04:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:56 INFO - 'LOGNAME': 'cltbld', 04:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:56 INFO - 'MOZ_NO_REMOTE': '1', 04:27:56 INFO - 'NO_EM_RESTART': '1', 04:27:56 INFO - 'PAGER': '/bin/cat', 04:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:56 INFO - 'PWD': '/builds/slave/test', 04:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:56 INFO - 'SHELL': '/bin/bash', 04:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:27:56 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:56 INFO - 'USER': 'cltbld', 04:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:56 INFO - 'XPC_FLAGS': '0x0', 04:27:56 INFO - 'XPC_SERVICE_NAME': '0', 04:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:27:56 INFO - Cleaning up... 04:27:56 INFO - Return code: 0 04:27:56 INFO - Running post-action listener: _resource_record_post_action 04:27:56 INFO - Running post-action listener: _start_resource_monitoring 04:27:56 INFO - Starting resource monitoring. 04:27:56 INFO - [mozharness: 2017-01-10 12:27:56.370875Z] Finished create-virtualenv step (success) 04:27:56 INFO - [mozharness: 2017-01-10 12:27:56.371842Z] Running install step. 04:27:56 INFO - Running pre-action listener: _resource_record_pre_action 04:27:56 INFO - Running main action method: install 04:27:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:27:56 INFO - Reading from file tmpfile_stdout 04:27:56 INFO - Detecting whether we're running mozinstall >=1.0... 04:27:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:27:56 INFO - Reading from file tmpfile_stdout 04:27:56 INFO - Output received: 04:27:56 INFO - Usage: mozinstall [options] installer 04:27:56 INFO - Options: 04:27:56 INFO - -h, --help show this help message and exit 04:27:56 INFO - -d DEST, --destination=DEST 04:27:56 INFO - Directory to install application into. [default: 04:27:56 INFO - "/builds/slave/test"] 04:27:56 INFO - --app=APP Application being installed. [default: firefox] 04:27:56 INFO - mkdir: /builds/slave/test/build/application 04:27:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:28:18 INFO - Reading from file tmpfile_stdout 04:28:18 INFO - Output received: 04:28:18 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:28:18 INFO - Running post-action listener: _resource_record_post_action 04:28:18 INFO - [mozharness: 2017-01-10 12:28:18.649442Z] Finished install step (success) 04:28:18 INFO - [mozharness: 2017-01-10 12:28:18.649618Z] Running run-tests step. 04:28:18 INFO - Running pre-action listener: _resource_record_pre_action 04:28:18 INFO - Running main action method: run_tests 04:28:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:18 INFO - 2017-01-10 04:28:18.664 screenresolution[1632:7876] starting screenresolution argv=screenresolution get 04:28:18 INFO - 2017-01-10 04:28:18.667 screenresolution[1632:7876] Display 0: 1600x1200x32@60 04:28:18 INFO - 2017-01-10 04:28:18.672 screenresolution[1633:7879] starting screenresolution argv=screenresolution list 04:28:18 INFO - Available Modes on Display 0 04:28:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:18 INFO - Intel Iris: 04:28:18 INFO - Chipset Model: Intel Iris 04:28:18 INFO - Type: GPU 04:28:18 INFO - Bus: Built-In 04:28:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:18 INFO - Vendor: Intel (0x8086) 04:28:18 INFO - Device ID: 0x0a2e 04:28:18 INFO - Revision ID: 0x0009 04:28:18 INFO - Displays: 04:28:18 INFO - Display: 04:28:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:18 INFO - Display Serial Number: 1600x1200 60 04:28:18 INFO - Main Display: Yes 04:28:18 INFO - Mirror: Off 04:28:18 INFO - Online: Yes 04:28:18 INFO - Rotation: Supported 04:28:18 INFO - Return code: 0 04:28:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:28:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:28:18 INFO - Python 2.7.3 04:28:18 INFO - Return code: 0 04:28:18 INFO - grabbing minidump binary from tooltool 04:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b0dd30>, '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 0x109a34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b02be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:28:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:28:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:28:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:28:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:28:19 INFO - Return code: 0 04:28:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:28:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:28:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:28:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:28:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0150 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fmx333y9o3/Render', 04:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:19 INFO - 'HOME': '/Users/cltbld', 04:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:19 INFO - 'LOGNAME': 'cltbld', 04:28:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:19 INFO - 'MOZ_NO_REMOTE': '1', 04:28:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:19 INFO - 'NO_EM_RESTART': '1', 04:28:19 INFO - 'PAGER': '/bin/cat', 04:28:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:19 INFO - 'PWD': '/builds/slave/test', 04:28:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:19 INFO - 'SHELL': '/bin/bash', 04:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zoP3Ycc2Hb/Listeners', 04:28:19 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 04:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:19 INFO - 'USER': 'cltbld', 04:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:19 INFO - 'XPC_FLAGS': '0x0', 04:28:19 INFO - 'XPC_SERVICE_NAME': '0', 04:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:28:19 INFO - mozversion application_buildid: 20170110030221 04:28:19 INFO - mozversion application_changeset: 8f3b24109e3412b36f97277e31ad66856dc609d6 04:28:19 INFO - mozversion application_display_name: Nightly 04:28:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:28:19 INFO - mozversion application_name: Firefox 04:28:19 INFO - mozversion application_remotingname: firefox 04:28:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:28:19 INFO - mozversion application_vendor: Mozilla 04:28:19 INFO - mozversion application_version: 53.0a1 04:28:19 INFO - mozversion platform_buildid: 20170110030221 04:28:19 INFO - mozversion platform_changeset: 8f3b24109e3412b36f97277e31ad66856dc609d6 04:28:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:28:19 INFO - mozversion platform_version: 53.0a1 04:28:19 INFO - using testdate: 1484051299 04:28:19 INFO - actual date: 1484051299 04:28:19 INFO - starting webserver on 'localhost:49236' 04:28:19 INFO - SUITE-START | Running 2 tests 04:28:19 INFO - TEST-START | dromaeo_css 04:28:19 INFO - operating with platform_type : mac_ 04:28:19 INFO - Initialising browser for dromaeo_css test... 04:28:19 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpgHOGTw/profile http://localhost:49236/getInfo.html) 04:28:20 INFO - PROCESS | 1643 | 1484051300230 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 04:28:21 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 04:28:21 INFO - PROCESS | 1643 | colorDepth:24 04:28:21 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 04:28:21 INFO - PROCESS | 1643 | __metrics 04:28:22 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:28:22 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:28:22 INFO - TEST-INFO | 1643: exit 0 04:28:22 INFO - Browser initialized. 04:28:22 INFO - Running cycle 1/1 for dromaeo_css test... 04:28:22 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpgHOGTw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:34 INFO - PROCESS | 1648 | RSS: Main: 226332672 04:28:34 INFO - PROCESS | 1648 | 04:31:39 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 04:31:40 INFO - PROCESS | 1648 | RSS: Main: 381423616 04:31:40 INFO - PROCESS | 1648 | 04:33:23 INFO - PROCESS | 1648 | ************************* 04:33:23 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 04:33:23 INFO - PROCESS | 1648 | 04:33:23 INFO - PROCESS | 1648 | Full message: ReferenceError: fetch is not defined 04:33:23 INFO - PROCESS | 1648 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:33:23 INFO - PROCESS | 1648 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:33:23 INFO - PROCESS | 1648 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:33:23 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:33:23 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:33:23 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:33:23 INFO - PROCESS | 1648 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:33:23 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:33:23 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:33:23 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:33:23 INFO - PROCESS | 1648 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:33:23 INFO - PROCESS | 1648 | 04:33:23 INFO - PROCESS | 1648 | ************************* 04:34:43 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 04:34:44 INFO - PROCESS | 1648 | RSS: Main: 368959488 04:34:44 INFO - PROCESS | 1648 | 04:37:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 04:37:47 INFO - PROCESS | 1648 | RSS: Main: 342618112 04:37:47 INFO - PROCESS | 1648 | 04:40:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 04:40:50 INFO - PROCESS | 1648 | RSS: Main: 347602944 04:40:50 INFO - PROCESS | 1648 | 04:43:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 04:43:54 INFO - PROCESS | 1648 | RSS: Main: 324001792 04:43:54 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 04:46:57 INFO - PROCESS | 1648 | RSS: Main: 455069696 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | __start_tp_report 04:46:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 04:46:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 04:46:57 INFO - PROCESS | 1648 | |i|pagename|runs| 04:46:57 INFO - PROCESS | 1648 | |0;dojo.html;3210.3579284143175;3281.114099193218;3311.387393332233;3324.119108436263;3357.6306606273306;5463.945360546403;5528.585356098289;5540.113581826909;5572.080606699895;5580;5567.554595632349;5570.554355651539;5639.718014099289;5655.3779084300795;5687.744051517671;5128.71792051437;5189.065968125736;5214.8696282592855;5223.608229382811;5256.973715131419;5094.159463688506;5208.7395630218525;5214.035403450373;5218.895622087556;5226.346706661668;4345.326474396474;5187.584993200554;5212.374515058179;5212.869678258055;5254.159334506479;5052.520010598987;5109.080365534202;5179.896402071938;5197.506236907488;5201.1678131498775;5064.544191022789;5119.924815788689;5120.308758317654;5178.40448348441;5211.635185537014;5549.972250138764;5552.639078459887;5590.524805391547;5596.860078498029;5600.663960162403;6166.260048794128;6199.907001394983;6202.937970620326;6204.906926396107;6228.283747369065;6074.179985701918;6094.8171554853425;6106.248323414558;6119.136988619962;6243;5695.914561281584;5703.372629010805;5710.114932185518;5738.741756620962;5772.509336706343;5222.3733152022005;6338.239411270585;6339.4611458025665;6353.174087368612;6356.9364306357265;5849;5870.73581688825;5942.465178133989;6014.939850601526;6032.607880487754;6129.645238677085;6148.708771158017;6168.475679567197;6182.474489668429;6250.062490626351;5957.225560677084;6033.064874944303;6153.107799369156;6171.6297022178815;6181.35095814933;6082.908756368658;6115.724792384264;6126.693665316717;6131.877362452726;6205.720742566596;4836.806527738921;4845.200541910615;4855.441624213261;4876.073546026315;4891.51084891508;4268.274393353137;4292.635126014324;4303.935440968388;4329.718568293051;4342.218400687906;5661.943380566142;5759.020966435716;5779.768809247583;5781.971090144522;5790.652560846363;6144.969275153685;6151.3541078186145;6223.042209977774;6262.935300998841;6283.08895210192;5708.743106560215;5719.7712091516705;5725.6850873202375;5740.39725828782;5751.396103409165;5203.14148165556;5263;5270.209468579744;5272.103742363807;5322;6295;6301.873962520724;6316.305206427198;6319.3364696706185;6322.0200868865395;6092.634441933498;6251.624902505864;6260.154879091265;6281.905771413341;6282.340354262737;6150.569460137747;6189.659568723763;6268.247810262797;6290.308066112815;6335.809925702052;6112.572119951738;6169.167162433194;6189.721462534107;6194.38056194377;6210.037444196157;5992.940070599413;6182.876342473035;6199.597026193283;6216.844578885383;6249.500039996698;4884.804607815648;5850.912236316543;5924.31870334917;6013.9097913532205;6078.635281883101;5887.293524777054;5919.467247947533;5968.492678122235;6040.879182416415;6098.908516372347;5827.3881242470625;5832.3292821326095;5845.883082338414;5854.4730974210825;5858.238429004202;5866.560007999332;5943;5947.672877991752;5955.761769529344;6019.638821670714;5845.970770146122;5862.208601838868;5877.500412464989;5886.323072846506;5914.112883067574 04:46:57 INFO - PROCESS | 1648 | |1;ext.html;451.02803458546845;484.08266335294616;499.7256994663607;501.32822018495233;527.0933993531128;30586;32495.512567311478;37845.432318515246;38762.41856372156;39246.43014279433;42399.788001059955;42781.78609106958;42833.35749963753;42855.571444285546;42858.14283714324;9175.027369115673;9181.540922953858;9244.8613270801;9323.160915517603;9479.383840050376;8606;8817.250533704642;9273.582688778988;11222.943885280562;11488.827667584992;11456.140789440826;11523.827142592867;11538;11544.422778861024;11559.479823407924;9038.412503187337;11348.60279890204;11398.829017564743;11445;11456;8403.327733781283;8618.65525378984;11015.118790496741;11246.987771100641;11272;39505.80247098746;40154.99612509681;40307.395389069185;40741.796291018654;40762.38856417156;3639.2357604902736;3656.981715091434;3680.4479328100733;3719.1074142205675;3749.3063783200196;24785.256442306578;30475;30677.69322306771;30925.22686932822;31224.531632025537;2651.602259661047;3161.6996385343355;3187.6930458511897;3212.068500134958;3247.447933851251;450908;451625.7418712885;453049;453868;459301;60573.09140362898;61674;61757;61798.38201617926;61912.69043654753;62108.378916211164;62235.37764622386;62277;64871.67564162149;64880.675596622656;15332.540023799304;15887.761683574756;15910.284037218353;15910.522684319489;15914.204289785464;11505.712357191138;12645;12735.299558524184;12738.80891786624;12770.80843787344;15545.067295962279;16276.593085172966;18180;18399.356022539414;18672.906635466737;6701.932980670228;6743.325667433315;6779.288174741581;6793.082933803972;6793.218779840382;863.4646519158036;872.8777971084053;874.226309715906;915.9587818548181;928.5078908178541;28143.859280703466;28347.71652283492;29866;30385.696143038287;30595.2351191222;1025.9692209233735;1025.99487002566;1061.0821639281965;1066.653337665262;1067.7384040910026;13999.300034998209;14644.169478815149;14667.559973200821;14893.627659308604;14954.401823927139;585305;588395;591709;592264;592506;52542;52643;52675.209871851956;52713;52811.73594132005;1307.4378017452586;1360.7006458579294;1398.2659103970498;1445.0318286748113;1451.1945870042227;26470;27102.915883364443;27484.725152749015;27683;27824.721752782214;41980.79009604932;44326.77836610797;44379.778101110576;44420;44581;509669;511846;511867;512461;513127;23574.29277121689;24165.39586510281;24466.388340291636;24482.877585611957;24547;38798.6120138795;40716.1856762869;41439.58560414478;41642.58357416387;41864.3720344189;34057.318853623285;34166.316673666886;34567.30865382628;34622.13444663904;35158.82420587881;32230.871919482706;34258;34373.484397733555;34966.47550286799;35092;32941.011769646946;33423.498647519824;34084;34515.96452106441;34547.82726086354;26997.1900842975;30546.93085742077;30643.846780766846;30736.588953152976;30942.69057309398;18657.626847463245;22372.105115795184;22547.66178507291;23222.883885580464;23311.53376932486 04:46:57 INFO - PROCESS | 1648 | |2;jquery.html;330.2009137886315;355.7794167616078;359.8272829042059;364.5024541500852;377.98488060477564;15366.00241968544;15640.921795391021;16406.015639061665;20689.896550517264;21306.680399794015;20467.897660511677;20814.583708325823;21276.680849787226;21504.677429838564;21504.924753762327;5110.361204849394;5909.143174239733;6165.321814600413;6170.734999325127;6175.3207147214;6033.7888173913925;6121.66330851803;6171.13604095427;6171.4445699887;6173.135760993465;5836.182934389189;6016.7593296268315;6112.388761123896;6113.633182009072;6124.356942521038;5903.409659034062;6005.7597696092325;6054.485368743662;6066.726997285133;6069.484093852028;5027.5977921766535;5228.189630607262;5288.391834938954;5309.548688361494;5317.521423071903;25916.8704156478;26434.6034809478;26447.867760661265;26667.199984000512;26723.19830405091;3029.091272618228;3070.8311042892633;3157.0371036833985;3204.13488358142;3217.7747557670737;14838.035527690774;15119.773203401957;16064.196790160446;16188.595285117848;16417.42539011141;1804.9729254061199;1863.245385618813;1867.8505719542404;1892.6025534637743;1895.1661269041715;104754.09590184843;128811;133685;135916;139017.30491347477;11354.772904542027;11852.703682407837;11853.88146118545;11865.466054027589;11872.821907671392;15155.166465844484;15237.6190595236;15655.608609784618;15656.060636361855;15679.372825086868;7630.580318082559;8366.121557236524;8693.087225841322;8768.342374321825;8856.601452934634;7009.719611215596;8490.193431623986;8520.914790852012;8706.912930870736;8712.564371781513;4101.5488296287385;4515.277555591089;4680.602148817388;4752.097101550694;4824.131656301829;1945.9805401946082;1960.9901950490441;1983.7917018712828;1987.3739771971782;1990.572027014199;1014.4775440648073;1026.6817286641165;1027.1269420992096;1036.326387847905;1063.144168943992;20681.58636827255;21298.57402851965;21630;21713.45716357072;21741;873.0091346321843;888.6845169964698;1015.8527013582989;1027.7636143686993;1034.8292531732332;10686.28034859938;10979.28634638746;11090.390028548345;11138.33170009802;11146.05258553032;664818.6759066174;670546;680750;685114;695802;148139;150169;158321.16788321306;161276;162367;959.69289827256;1018.8064267789097;1050.4642632257603;1054.667779649408;1058.730023843915;18204.635907281514;18656.533586660444;18703.1583578742;18739.437816865517;19023;53697.463025369245;56310;56469.71765141148;58401.80731322071;61034;69418.26454338682;77577;87807.12192878245;87932;89942;8006.759797206093;8066.435349525477;8082.676692932217;8482.491050536988;8530.10433904431;7533.924660753322;11230.213885028297;11452.713682157679;12664.6833829155;13370.799438008633;10847.67456976292;11078.889211107786;11442.37066961325;11447.370394628042;11543.018843398406;9843.310968232156;11056.004959553678;11097.889021109686;11261.7747645045;11265.271899704583;11352.716182095513;11410.486528106088;11641.301521908712;11747.353895535827;11802.704932376759;8098.797530061795;8290.212429819157;8372.748817535483;8404.789880253042;8563.100874408346;8247.587620618826;8796.736097917073;10701.946490267499;11030;11042.668719938414 04:46:57 INFO - PROCESS | 1648 | |3;mootools.html;1337.8662133786622;1453.8255409350872;1475.9335829887655;1481.562938933015;1485.3167542930246;8086.838263234732;8524.829503409928;8534.573271336423;8551.358648101394;8567.700130495434;7530.6987720491115;7740.148583655793;7749.53502789833;8083.312918401943;8314.70898518552;2208.7901649343285;2280.4184932842104;2281.0191617604423;2284.132029828663;2285.611446054175;2288.4507718147693;2307.9192228272013;2321.6865723127416;2327.313442534448;2331.5803155432013;2015.868968517049;2098.339023207692;2170.2404158544464;2275.215050807477;2309.93070207894;1918.3765276285123;2265.229821860567;2278.931632051033;2282.5549017941507;2286.142696488817;2267.206477732797;2288.4736510602656;2289.4161988692777;2294.105298933417;2295.7130358705163;8353.832923341499;8419.158084191593;8468.872966905501;8478.872816907751;8525.062243153248;5016.2224855147515;5160.7419629018395;5169.948300516984;5171.637985341028;5175.585953123741;3282.573265475473;3429.6741809528053;3503.982480087583;3524.9647503524893;3543.6633519815578;1165.353228957928;1229.2931564350533;1231.4483750474378;1251.2867665430708;1260.8297879786203;10396.84404733922;10496.422696751675;10867.83698244527;10886.72783180419;10900.672979810619;4342.956570434319;4441.4892287387365;4473.038296766211;4487.798049087799;4497.775111244425;8420.957895210484;8608.268297194809;8719;8726.607302671395;8730.083341249077;5123.309307928374;5129.333186685707;5137.638525790669;5137.768800403991;5146.511081447256;4650.95349046512;4807.6153907688;4819.783109760069;4856.053069651384;4874.8537543873745;3582.6059133495296;3677.2093999790172;3686.612905644922;3697.334479793663;3728.0307120148955;1158.1082566423975;1159.8086315757805;1165.877582853805;1167.258790667932;1168.7954607943575;788.3062904643991;794.7417089445953;884.062893333069;905.2441211588266;916.9403988740711;5438.592105592017;5525;5803.6807975560905;5888.174665853599;5999.100134979788;1009.6970908727352;1011.7622358745689;1013.4932533733133;1037.1029059863165;1053.252190944438;3775.8300876460626;3807.8476860925807;3870.748401353903;3995.780232087263;4005.8583303758287;9682.90317096834;10027.04743049409;10185.898141018495;10192.286539942133;10211.693649190536;9662.468564229035;9736.853947190797;9749.415035097916;10169.54237059334;10179.694609161737;6624.569402988886;7577.280158384945;7909.604519773989;7919.247671471201;7919.722809701633;5441.26542916717;5592.245046918777;5608.579356548194;5658.5190258826815;5691.146328050825;9181.265498760216;9301.581428835854;9377.249820014245;9386.014468480711;9400.388974716621;8535.91464085376;8918.420302680304;8946.194842463949;9512.33413661037;9527.714168574954;6710.362515561014;6877.724891004504;7152.570845749272;7155.677994490156;7240.384567311838;6166.969165154145;7877.527348359117;7966.721164759206;8079.313258373104;8079.555624440713;7110.4667149962925;7145.678444469907;7206.89189662145;7207.8558428832175;7287.562746235243;6162.630242185483;7006.544574602634;7023.964880175567;7025.297470253138;7073.6816843243205;6407.647579383179;6434.64609446485;6451.516136289703;6483.059956306403;6486.351364863476;4324.654027677715;4997.5002499749735;5011.5489605935645;5056.7471626419265;5064.012517559041;4840;4936.432310284364;5035.244713293036;5104.41299250591;5108.208227724708 04:46:57 INFO - PROCESS | 1648 | |4;prototype.html;328.371169210961;348.2356228079367;350.75972958523414;356.1665702256719;366.2309150783354;4726.929096063562;4849.17564014118;4861.756912154396;5084.745762711864;5104.617153713472;3811.752236104658;4249.723767955081;4878.146324393235;4915.287283343913;4918.754062296889;3980.7412518186225;4032.334664780308;4036.829319497342;4048.4939382577177;4054.97972510137;3484.9651503484893;3691.280200360931;3988.8404463821557;4015.799210039501;4023.577524359929;3164.841757912107;3250.073728987239;3701.3152566775234;3986.2027594481074;4007.0583412897945;3075.600171977651;3597.5143355646915;3816.561095474028;4025.1547175093265;4039.333509970851;3386.2888793353427;3554.5379100717;3563.073600863768;3563.465480177994;3574.28514297139;4427.5572442755465;4973.170658592863;4979.526944940225;4980.551750342451;5013.448520662724;3614.566252049771;4147.398627199039;4385.736855788663;4430.379746835446;4433.357163211349;5276.868078298035;5324.843438407451;5343.919841202385;5361.222622719683;5396.757145928404;3177.4598318285716;3701.130234394915;3714.220013797106;3724.4971928789537;3754.5682246541724;4948.515445366375;5006.549410553068;5154.561862241677;5579.967705974408;5771.538276937835;3165.509346051366;3351.6815902489225;3741.5323084614424;3756.7933763643264;3760.4735337052834;3228.806271623663;3399.9490007649906;3521.3837578423672;3796.1838204786104;3805.6384643458828;4382.9780851096175;4397.2304846651705;4403.801828917706;4418.469783625986;4421.2483877740215;5087.287779710843;5392.272043274186;5449.455054494519;5459.890802183934;5464.617476776588;1062.3838173859297;1230.5816022552376;1245.2815115141047;1252.7807633664072;1254.2599866079056;748.0649188514357;794.8211652378169;811.7402431221952;852.3677158242022;854.717923115327;4187.973946383156;4762;4886.486918873538;5070.859056712284;5141.228815677603;4702.576768090888;4857.478328212018;4865.132219885199;4894.632902532253;5521.5306698931045;3562.201088450316;4240.98216428055;4772.926091629356;4914.410270767459;5084.805070808357;2260.6269965455517;2464.162184857157;2751.147144385247;2774.4728501584636;2789.6373471448583;4684.391029166255;4761.35721677576;4897.881713609287;4999.825006124768;5389.7035663038905;4668.976655116703;4693.6949098308505;4815.7351345676325;4958.925616115761;5011.924821127686;5256.185291279858;5350.839474815762;5441.5102640762525;5451.400345962021;5451.563874889999;4466.352378905106;4550.9317360238965;5222.138347172674;5286.709230992178;5299.655522391032;4405.31717583775;4475.015496590745;5048.318477005557;5204.2714020036465;5222.3472065991755;5677.318721753416;5723.279868085778;5737;5740.799072032459;5752.7698892043845;3528.629493903206;3623.927521449609;3813.6949044075855;3823.502944617182;3829.9042523937123;4587.9354290570045;5373.408925018167;5567.4967758704115;5618.325800903917;5622.353429355677;4079.265732168238;5270.41887433776;5337.386200586827;5361.51746342831;5438.401775804579;4669.4863565007145;4675.625949924066;5391.412122181297;5393;5396.811111611075;4375.024983011583;4555.886102847456;4918.532739389752;5245.449227831175;5297.576193904555;4731.550502702238;4774.689645173053;5291.629585929102;5321.255024296524;5336.78652853897;4667.929981050219;5289.6032797539565;5322.562908014892;5331.773692050772;5332.20016997438 04:46:57 INFO - PROCESS | 1648 | |5;yui.html;709.5068927095668;724.0984973707734;740.3817812126874;783.9372850171986;831.8086840026792;14785.334659940301;15766.369345226192;15772.76340854888;15805.367785288603;15810.367585296603;14332.713345733106;14529.346179421926;14551.563453096423;14568.927155364208;14590.781138282935;683.9076724642184;704.9541779784298;711.1146622455025;846.3102571404316;854.5385491834402;862.7239283429274;865.2299453486402;867.488181972636;876.3077169036453;877.2324216310728;817.5666896544841;840.6343240690288;858.8668756342777;861.4142383179435;863.9913600864005;823.851706692795;864.0236532717988;866.9739907802776;867.9479231246082;869.2871845087;532.199040444133;551.0549407765661;660.9801705948829;678.0947435174048;681.3799442507294;14931.178785166812;15378.231088445533;15454.845451545452;15464.536063917987;15477.22613869302;13820;13959.720805583933;14016;14054.648633784134;14054.859451405457;12453.626391208278;12710.682232944158;12825.23048617086;12859.678508037281;13115.934420327932;4346.065595896897;4427.512973572937;4580.679352445331;4638.281066434708;4642.9071418571775;97021.5148924258;97164;97427;99221.50389248008;103990;10906.72733181661;11054.447277636247;11164.609238676607;11205.71985700364;11295.209335346612;22729.88635056814;22755.31734047981;22769.658455123186;22787.202447914242;22796.4300892479;7703.499272547267;7795.84633638094;7804.726834560764;7840.41196910234;7847.607619618996;4183.811728472165;4212.452381190487;4234.576542345802;4264.722793018444;4281.957180428156;3514.279572687593;3517.891864062812;3626;3626.5829429615405;3627.6009638939695;653.761377097356;654.4404534123352;667.9866402672017;671.6238906212536;671.9932800672028;581.340178896955;632.5319263744888;652.425865238587;657.8815813153678;659.2329735267483;9019.909800901907;9050.773730656787;9353.672621458352;9783.923768385472;9929.304948653526;487.8024400117958;617.2099712368176;624.5378419969281;640.1869625575494;652.8596351784389;4737.771367468245;4834;4879;4885.477946665867;4888.780004899788;74622.64198111098;103428.48285758523;106691.46654266835;106763;109997.90002100037;91560.08439915649;92377.538112309;92464;93724.53137734268;94031.52984235036;69744.65127674329;70075.29924700789;70862.2913770866;74370.62814685891;82939.5853020731;8724.258438032839;9697.5151242439;10670.253082284165;10791.190660700637;10977.670669879917;49411.505884942126;50750.746246270006;50992;51233;53310.4668953321;66761.66619166873;67684;68253;68679;69511;10443.94778026105;10795.136389089012;10798.352098874093;11532.654020379403;13577.049606527447;12470.817606918934;12486.875131248571;12575;12806.551770687982;13039.022073344728;11889.405529723306;12116;12238;12421.93789031049;12511.499540018662;11771.646850594496;11849.644510664693;12015.75968480643;12036.75926481448;12508.999280057755;11889.583864564698;12002.75994480123;12084.818727719266;12461.314627695201;14075.366608502438;10516.842247366449;10968.835467467834;11056;11481.770364592829;11655.475503602527;11402.144839137263;11464.25482343645;11498.425078746197;11600.30398176112;11751.412429378668 04:46:57 INFO - PROCESS | 1648 | __end_tp_report 04:46:57 INFO - PROCESS | 1648 | __start_cc_report 04:46:57 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1566 04:46:57 INFO - PROCESS | 1648 | __end_cc_report 04:46:57 INFO - PROCESS | 1648 | __startTimestamp1484052417851__endTimestamp 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | ------- Summary: start ------- 04:46:57 INFO - PROCESS | 1648 | Number of tests: 6 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5665.21 Median:5850.91 stddev:650.32 (11.1%) stddev-sans-first:623.15 04:46:57 INFO - PROCESS | 1648 | Values: 3210.4 3281.1 3311.4 3324.1 3357.6 5463.9 5528.6 5540.1 5572.1 5580.0 5567.6 5570.6 5639.7 5655.4 5687.7 5128.7 5189.1 5214.9 5223.6 5257.0 5094.2 5208.7 5214.0 5218.9 5226.3 4345.3 5187.6 5212.4 5212.9 5254.2 5052.5 5109.1 5179.9 5197.5 5201.2 5064.5 5119.9 5120.3 5178.4 5211.6 5550.0 5552.6 5590.5 5596.9 5600.7 6166.3 6199.9 6202.9 6204.9 6228.3 6074.2 6094.8 6106.2 6119.1 6243.0 5695.9 5703.4 5710.1 5738.7 5772.5 5222.4 6338.2 6339.5 6353.2 6356.9 5849.0 5870.7 5942.5 6014.9 6032.6 6129.6 6148.7 6168.5 6182.5 6250.1 5957.2 6033.1 6153.1 6171.6 6181.4 6082.9 6115.7 6126.7 6131.9 6205.7 4836.8 4845.2 4855.4 4876.1 4891.5 4268.3 4292.6 4303.9 4329.7 4342.2 5661.9 5759.0 5779.8 5782.0 5790.7 6145.0 6151.4 6223.0 6262.9 6283.1 5708.7 5719.8 5725.7 5740.4 5751.4 5203.1 5263.0 5270.2 5272.1 5322.0 6295.0 6301.9 6316.3 6319.3 6322.0 6092.6 6251.6 6260.2 6281.9 6282.3 6150.6 6189.7 6268.2 6290.3 6335.8 6112.6 6169.2 6189.7 6194.4 6210.0 5992.9 6182.9 6199.6 6216.8 6249.5 4884.8 5850.9 5924.3 6013.9 6078.6 5887.3 5919.5 5968.5 6040.9 6098.9 5827.4 5832.3 5845.9 5854.5 5858.2 5866.6 5943.0 5947.7 5955.8 6019.6 5846.0 5862.2 5877.5 5886.3 5914.1 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:64760.99 Median:34557.57 stddev:139175.82 (402.7%) stddev-sans-first:139482.49 04:46:57 INFO - PROCESS | 1648 | Values: 451.0 484.1 499.7 501.3 527.1 30586.0 32495.5 37845.4 38762.4 39246.4 42399.8 42781.8 42833.4 42855.6 42858.1 9175.0 9181.5 9244.9 9323.2 9479.4 8606.0 8817.3 9273.6 11222.9 11488.8 11456.1 11523.8 11538.0 11544.4 11559.5 9038.4 11348.6 11398.8 11445.0 11456.0 8403.3 8618.7 11015.1 11247.0 11272.0 39505.8 40155.0 40307.4 40741.8 40762.4 3639.2 3657.0 3680.4 3719.1 3749.3 24785.3 30475.0 30677.7 30925.2 31224.5 2651.6 3161.7 3187.7 3212.1 3247.4 450908.0 451625.7 453049.0 453868.0 459301.0 60573.1 61674.0 61757.0 61798.4 61912.7 62108.4 62235.4 62277.0 64871.7 64880.7 15332.5 15887.8 15910.3 15910.5 15914.2 11505.7 12645.0 12735.3 12738.8 12770.8 15545.1 16276.6 18180.0 18399.4 18672.9 6701.9 6743.3 6779.3 6793.1 6793.2 863.5 872.9 874.2 916.0 928.5 28143.9 28347.7 29866.0 30385.7 30595.2 1026.0 1026.0 1061.1 1066.7 1067.7 13999.3 14644.2 14667.6 14893.6 14954.4 585305.0 588395.0 591709.0 592264.0 592506.0 52542.0 52643.0 52675.2 52713.0 52811.7 1307.4 1360.7 1398.3 1445.0 1451.2 26470.0 27102.9 27484.7 27683.0 27824.7 41980.8 44326.8 44379.8 44420.0 44581.0 509669.0 511846.0 511867.0 512461.0 513127.0 23574.3 24165.4 24466.4 24482.9 24547.0 38798.6 40716.2 41439.6 41642.6 41864.4 34057.3 34166.3 34567.3 34622.1 35158.8 32230.9 34258.0 34373.5 34966.5 35092.0 32941.0 33423.5 34084.0 34516.0 34547.8 26997.2 30546.9 30643.8 30736.6 30942.7 18657.6 22372.1 22547.7 23222.9 23311.5 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:38768.94 Median:21671.73 stddev:113712.90 (524.7%) stddev-sans-first:113995.26 04:46:57 INFO - PROCESS | 1648 | Values: 330.2 355.8 359.8 364.5 378.0 15366.0 15640.9 16406.0 20689.9 21306.7 20467.9 20814.6 21276.7 21504.7 21504.9 5110.4 5909.1 6165.3 6170.7 6175.3 6033.8 6121.7 6171.1 6171.4 6173.1 5836.2 6016.8 6112.4 6113.6 6124.4 5903.4 6005.8 6054.5 6066.7 6069.5 5027.6 5228.2 5288.4 5309.5 5317.5 25916.9 26434.6 26447.9 26667.2 26723.2 3029.1 3070.8 3157.0 3204.1 3217.8 14838.0 15119.8 16064.2 16188.6 16417.4 1805.0 1863.2 1867.9 1892.6 1895.2 104754.1 128811.0 133685.0 135916.0 139017.3 11354.8 11852.7 11853.9 11865.5 11872.8 15155.2 15237.6 15655.6 15656.1 15679.4 7630.6 8366.1 8693.1 8768.3 8856.6 7009.7 8490.2 8520.9 8706.9 8712.6 4101.5 4515.3 4680.6 4752.1 4824.1 1946.0 1961.0 1983.8 1987.4 1990.6 1014.5 1026.7 1027.1 1036.3 1063.1 20681.6 21298.6 21630.0 21713.5 21741.0 873.0 888.7 1015.9 1027.8 1034.8 10686.3 10979.3 11090.4 11138.3 11146.1 664818.7 670546.0 680750.0 685114.0 695802.0 148139.0 150169.0 158321.2 161276.0 162367.0 959.7 1018.8 1050.5 1054.7 1058.7 18204.6 18656.5 18703.2 18739.4 19023.0 53697.5 56310.0 56469.7 58401.8 61034.0 69418.3 77577.0 87807.1 87932.0 89942.0 8006.8 8066.4 8082.7 8482.5 8530.1 7533.9 11230.2 11452.7 12664.7 13370.8 10847.7 11078.9 11442.4 11447.4 11543.0 9843.3 11056.0 11097.9 11261.8 11265.3 11352.7 11410.5 11641.3 11747.4 11802.7 8098.8 8290.2 8372.7 8404.8 8563.1 8247.6 8796.7 10701.9 11030.0 11042.7 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5389.21 Median:5106.31 stddev:2925.20 (57.3%) stddev-sans-first:2917.55 04:46:57 INFO - PROCESS | 1648 | Values: 1337.9 1453.8 1475.9 1481.6 1485.3 8086.8 8524.8 8534.6 8551.4 8567.7 7530.7 7740.1 7749.5 8083.3 8314.7 2208.8 2280.4 2281.0 2284.1 2285.6 2288.5 2307.9 2321.7 2327.3 2331.6 2015.9 2098.3 2170.2 2275.2 2309.9 1918.4 2265.2 2278.9 2282.6 2286.1 2267.2 2288.5 2289.4 2294.1 2295.7 8353.8 8419.2 8468.9 8478.9 8525.1 5016.2 5160.7 5169.9 5171.6 5175.6 3282.6 3429.7 3504.0 3525.0 3543.7 1165.4 1229.3 1231.4 1251.3 1260.8 10396.8 10496.4 10867.8 10886.7 10900.7 4343.0 4441.5 4473.0 4487.8 4497.8 8421.0 8608.3 8719.0 8726.6 8730.1 5123.3 5129.3 5137.6 5137.8 5146.5 4651.0 4807.6 4819.8 4856.1 4874.9 3582.6 3677.2 3686.6 3697.3 3728.0 1158.1 1159.8 1165.9 1167.3 1168.8 788.3 794.7 884.1 905.2 916.9 5438.6 5525.0 5803.7 5888.2 5999.1 1009.7 1011.8 1013.5 1037.1 1053.3 3775.8 3807.8 3870.7 3995.8 4005.9 9682.9 10027.0 10185.9 10192.3 10211.7 9662.5 9736.9 9749.4 10169.5 10179.7 6624.6 7577.3 7909.6 7919.2 7919.7 5441.3 5592.2 5608.6 5658.5 5691.1 9181.3 9301.6 9377.2 9386.0 9400.4 8535.9 8918.4 8946.2 9512.3 9527.7 6710.4 6877.7 7152.6 7155.7 7240.4 6167.0 7877.5 7966.7 8079.3 8079.6 7110.5 7145.7 7206.9 7207.9 7287.6 6162.6 7006.5 7024.0 7025.3 7073.7 6407.6 6434.6 6451.5 6483.1 6486.4 4324.7 4997.5 5011.5 5056.7 5064.0 4840.0 4936.4 5035.2 5104.4 5108.2 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4246.08 Median:4714.75 stddev:1294.65 (27.5%) stddev-sans-first:1264.44 04:46:57 INFO - PROCESS | 1648 | Values: 328.4 348.2 350.8 356.2 366.2 4726.9 4849.2 4861.8 5084.7 5104.6 3811.8 4249.7 4878.1 4915.3 4918.8 3980.7 4032.3 4036.8 4048.5 4055.0 3485.0 3691.3 3988.8 4015.8 4023.6 3164.8 3250.1 3701.3 3986.2 4007.1 3075.6 3597.5 3816.6 4025.2 4039.3 3386.3 3554.5 3563.1 3563.5 3574.3 4427.6 4973.2 4979.5 4980.6 5013.4 3614.6 4147.4 4385.7 4430.4 4433.4 5276.9 5324.8 5343.9 5361.2 5396.8 3177.5 3701.1 3714.2 3724.5 3754.6 4948.5 5006.5 5154.6 5580.0 5771.5 3165.5 3351.7 3741.5 3756.8 3760.5 3228.8 3399.9 3521.4 3796.2 3805.6 4383.0 4397.2 4403.8 4418.5 4421.2 5087.3 5392.3 5449.5 5459.9 5464.6 1062.4 1230.6 1245.3 1252.8 1254.3 748.1 794.8 811.7 852.4 854.7 4188.0 4762.0 4886.5 5070.9 5141.2 4702.6 4857.5 4865.1 4894.6 5521.5 3562.2 4241.0 4772.9 4914.4 5084.8 2260.6 2464.2 2751.1 2774.5 2789.6 4684.4 4761.4 4897.9 4999.8 5389.7 4669.0 4693.7 4815.7 4958.9 5011.9 5256.2 5350.8 5441.5 5451.4 5451.6 4466.4 4550.9 5222.1 5286.7 5299.7 4405.3 4475.0 5048.3 5204.3 5222.3 5677.3 5723.3 5737.0 5740.8 5752.8 3528.6 3623.9 3813.7 3823.5 3829.9 4587.9 5373.4 5567.5 5618.3 5622.4 4079.3 5270.4 5337.4 5361.5 5438.4 4669.5 4675.6 5391.4 5393.0 5396.8 4375.0 4555.9 4918.5 5245.4 5297.6 4731.6 4774.7 5291.6 5321.3 5336.8 4667.9 5289.6 5322.6 5331.8 5332.2 04:46:57 INFO - PROCESS | 1648 | 04:46:57 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:20163.66 Median:4609.48 stddev:28824.98 (625.3%) stddev-sans-first:28868.83 04:46:57 INFO - PROCESS | 1648 | Values: 709.5 724.1 740.4 783.9 831.8 14785.3 15766.4 15772.8 15805.4 15810.4 14332.7 14529.3 14551.6 14568.9 14590.8 683.9 705.0 711.1 846.3 854.5 862.7 865.2 867.5 876.3 877.2 817.6 840.6 858.9 861.4 864.0 823.9 864.0 867.0 867.9 869.3 532.2 551.1 661.0 678.1 681.4 14931.2 15378.2 15454.8 15464.5 15477.2 13820.0 13959.7 14016.0 14054.6 14054.9 12453.6 12710.7 12825.2 12859.7 13115.9 4346.1 4427.5 4580.7 4638.3 4642.9 97021.5 97164.0 97427.0 99221.5 103990.0 10906.7 11054.4 11164.6 11205.7 11295.2 22729.9 22755.3 22769.7 22787.2 22796.4 7703.5 7795.8 7804.7 7840.4 7847.6 4183.8 4212.5 4234.6 4264.7 4282.0 3514.3 3517.9 3626.0 3626.6 3627.6 653.8 654.4 668.0 671.6 672.0 581.3 632.5 652.4 657.9 659.2 9019.9 9050.8 9353.7 9783.9 9929.3 487.8 617.2 624.5 640.2 652.9 4737.8 4834.0 4879.0 4885.5 4888.8 74622.6 103428.5 106691.5 106763.0 109997.9 91560.1 92377.5 92464.0 93724.5 94031.5 69744.7 70075.3 70862.3 74370.6 82939.6 8724.3 9697.5 10670.3 10791.2 10977.7 49411.5 50750.7 50992.0 51233.0 53310.5 66761.7 67684.0 68253.0 68679.0 69511.0 10443.9 10795.1 10798.4 11532.7 13577.0 12470.8 12486.9 12575.0 12806.6 13039.0 11889.4 12116.0 12238.0 12421.9 12511.5 11771.6 11849.6 12015.8 12036.8 12509.0 11889.6 12002.8 12084.8 12461.3 14075.4 10516.8 10968.8 11056.0 11481.8 11655.5 11402.1 11464.3 11498.4 11600.3 11751.4 04:46:57 INFO - PROCESS | 1648 | -------- Summary: end -------- 04:46:57 INFO - PROCESS | 1648 | 04:46:58 INFO - TEST-INFO | 1648: exit 0 04:46:58 INFO - TEST-OK | dromaeo_css | took 1119222ms 04:46:58 INFO - TEST-START | kraken 04:46:58 INFO - operating with platform_type : mac_ 04:46:58 INFO - Initialising browser for kraken test... 04:46:58 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpdtmL5S/profile http://localhost:49236/getInfo.html) 04:46:58 INFO - PROCESS | 1657 | 1484052418991 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 04:47:00 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:47:00 INFO - PROCESS | 1657 | colorDepth:24 04:47:00 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:47:00 INFO - PROCESS | 1657 | __metrics 04:47:00 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:47:00 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:47:01 INFO - TEST-INFO | 1657: exit 0 04:47:01 INFO - Browser initialized. 04:47:01 INFO - Running cycle 1/1 for kraken test... 04:47:01 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpdtmL5S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:13 INFO - PROCESS | 1659 | RSS: Main: 225013760 04:47:13 INFO - PROCESS | 1659 | 04:47:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 04:47:17 INFO - PROCESS | 1659 | RSS: Main: 286228480 04:47:17 INFO - PROCESS | 1659 | 04:47:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 04:47:20 INFO - PROCESS | 1659 | RSS: Main: 288190464 04:47:20 INFO - PROCESS | 1659 | 04:47:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 04:47:24 INFO - PROCESS | 1659 | RSS: Main: 380755968 04:47:24 INFO - PROCESS | 1659 | 04:47:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 04:47:27 INFO - PROCESS | 1659 | RSS: Main: 284213248 04:47:27 INFO - PROCESS | 1659 | 04:47:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:47:29 INFO - PROCESS | 1659 | RSS: Main: 287682560 04:47:29 INFO - PROCESS | 1659 | 04:47:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 04:47:33 INFO - PROCESS | 1659 | RSS: Main: 328871936 04:47:33 INFO - PROCESS | 1659 | 04:47:36 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 04:47:36 INFO - PROCESS | 1659 | RSS: Main: 332120064 04:47:36 INFO - PROCESS | 1659 | 04:47:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 04:47:40 INFO - PROCESS | 1659 | RSS: Main: 330006528 04:47:40 INFO - PROCESS | 1659 | 04:47:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:47:42 INFO - PROCESS | 1659 | RSS: Main: 304218112 04:47:42 INFO - PROCESS | 1659 | 04:47:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:47:44 INFO - PROCESS | 1659 | RSS: Main: 305053696 04:47:44 INFO - PROCESS | 1659 | 04:47:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:47:47 INFO - PROCESS | 1659 | RSS: Main: 307929088 04:47:47 INFO - PROCESS | 1659 | 04:47:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:47:51 INFO - PROCESS | 1659 | RSS: Main: 326311936 04:47:51 INFO - PROCESS | 1659 | 04:47:54 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:47:55 INFO - PROCESS | 1659 | RSS: Main: 320462848 04:47:55 INFO - PROCESS | 1659 | 04:47:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 04:47:57 INFO - PROCESS | 1659 | RSS: Main: 364290048 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | __start_tp_report 04:47:57 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 04:47:57 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 04:47:57 INFO - PROCESS | 1659 | |i|pagename|runs| 04:47:57 INFO - PROCESS | 1659 | |0;ai-astar;103;115;114;114;114;115;113;113;114;114 04:47:57 INFO - PROCESS | 1659 | |1;audio-beat-detection;140;107;107;145;110;108;113;142;157;138 04:47:57 INFO - PROCESS | 1659 | |2;audio-dft;185;183;184;185;184;181;181;183;195;260 04:47:57 INFO - PROCESS | 1659 | |3;audio-fft;78;58;59;87;60;60;59;59;73;58 04:47:57 INFO - PROCESS | 1659 | |4;audio-oscillator;90;70;86;69;65;68;85;69;69;86 04:47:57 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;87;85;88;85;87;84;89;86;87;85 04:47:57 INFO - PROCESS | 1659 | |6;imaging-darkroom;94;93;93;93;94;94;94;93;92;94 04:47:57 INFO - PROCESS | 1659 | |7;imaging-desaturate;85;85;86;86;87;86;85;88;86;85 04:47:57 INFO - PROCESS | 1659 | |8;json-parse-financial;57;54;55;53;55;55;55;55;56;56 04:47:57 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;66;74;75;75;72;72;71;73;71;98 04:47:57 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;87;96;106;87;86;87;93;87;92;108 04:47:57 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;143;140;141;140;139;140;141;145;207;141 04:47:57 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;173;172;170;173;172;173;171;172;171;172 04:47:57 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;68;68;69;69;68;67;68;67;69;68 04:47:57 INFO - PROCESS | 1659 | __end_tp_report 04:47:57 INFO - PROCESS | 1659 | __start_cc_report 04:47:57 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2129 04:47:57 INFO - PROCESS | 1659 | __end_cc_report 04:47:57 INFO - PROCESS | 1659 | __startTimestamp1484052477186__endTimestamp 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | ------- Summary: start ------- 04:47:57 INFO - PROCESS | 1659 | Number of tests: 14 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.54 (3.1%) stddev-sans-first:0.71 04:47:57 INFO - PROCESS | 1659 | Values: 103.0 115.0 114.0 114.0 114.0 115.0 113.0 113.0 114.0 114.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:139.00 stddev:19.39 (14.0%) stddev-sans-first:19.96 04:47:57 INFO - PROCESS | 1659 | Values: 140.0 107.0 107.0 145.0 110.0 108.0 113.0 142.0 157.0 138.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:192.10 Median:184.50 stddev:24.18 (13.1%) stddev-sans-first:25.51 04:47:57 INFO - PROCESS | 1659 | Values: 185.0 183.0 184.0 185.0 184.0 181.0 181.0 183.0 195.0 260.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:65.10 Median:60.00 stddev:10.40 (17.3%) stddev-sans-first:9.92 04:47:57 INFO - PROCESS | 1659 | Values: 78.0 58.0 59.0 87.0 60.0 60.0 59.0 59.0 73.0 58.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:77.50 stddev:9.68 (12.5%) stddev-sans-first:8.78 04:47:57 INFO - PROCESS | 1659 | Values: 90.0 70.0 86.0 69.0 65.0 68.0 85.0 69.0 69.0 86.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.57 (1.8%) stddev-sans-first:1.64 04:47:57 INFO - PROCESS | 1659 | Values: 87.0 85.0 88.0 85.0 87.0 84.0 89.0 86.0 87.0 85.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 04:47:57 INFO - PROCESS | 1659 | Values: 94.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 92.0 94.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:0.99 (1.2%) stddev-sans-first:1.00 04:47:57 INFO - PROCESS | 1659 | Values: 85.0 85.0 86.0 86.0 87.0 86.0 85.0 88.0 86.0 85.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:1.10 (2.0%) stddev-sans-first:0.93 04:47:57 INFO - PROCESS | 1659 | Values: 57.0 54.0 55.0 53.0 55.0 55.0 55.0 55.0 56.0 56.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:73.50 stddev:8.59 (11.7%) stddev-sans-first:8.51 04:47:57 INFO - PROCESS | 1659 | Values: 66.0 74.0 75.0 75.0 72.0 72.0 71.0 73.0 71.0 98.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:87.00 stddev:8.14 (9.4%) stddev-sans-first:8.35 04:47:57 INFO - PROCESS | 1659 | Values: 87.0 96.0 106.0 87.0 86.0 87.0 93.0 87.0 92.0 108.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:141.00 stddev:20.91 (14.8%) stddev-sans-first:22.11 04:47:57 INFO - PROCESS | 1659 | Values: 143.0 140.0 141.0 140.0 139.0 140.0 141.0 145.0 207.0 141.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 04:47:57 INFO - PROCESS | 1659 | Values: 173.0 172.0 170.0 173.0 172.0 173.0 171.0 172.0 171.0 172.0 04:47:57 INFO - PROCESS | 1659 | 04:47:57 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 04:47:57 INFO - PROCESS | 1659 | Values: 68.0 68.0 69.0 69.0 68.0 67.0 68.0 67.0 69.0 68.0 04:47:57 INFO - PROCESS | 1659 | -------- Summary: end -------- 04:47:57 INFO - PROCESS | 1659 | 04:47:58 INFO - TEST-INFO | 1659: exit 0 04:47:58 INFO - TEST-OK | kraken | took 59600ms 04:47:58 INFO - SUITE-END | took 1178s 04:47:58 INFO - Completed test suite (00:19:39) 04:47:58 INFO - javascript benchmark 04:47:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.3579284143175, 3281.114099193218, 3311.387393332233, 3324.119108436263, 3357.6306606273306, 5463.945360546403, 5528.585356098289, 5540.113581826909, 5572.080606699895, 5580.0, 5567.554595632349, 5570.554355651539, 5639.718014099289, 5655.3779084300795, 5687.744051517671, 5128.71792051437, 5189.065968125736, 5214.8696282592855, 5223.608229382811, 5256.973715131419, 5094.159463688506, 5208.7395630218525, 5214.035403450373, 5218.895622087556, 5226.346706661668, 4345.326474396474, 5187.584993200554, 5212.374515058179, 5212.869678258055, 5254.159334506479, 5052.520010598987, 5109.080365534202, 5179.896402071938, 5197.506236907488, 5201.1678131498775, 5064.544191022789, 5119.924815788689, 5120.308758317654, 5178.40448348441, 5211.635185537014, 5549.972250138764, 5552.639078459887, 5590.524805391547, 5596.860078498029, 5600.663960162403, 6166.260048794128, 6199.907001394983, 6202.937970620326, 6204.906926396107, 6228.283747369065, 6074.179985701918, 6094.8171554853425, 6106.248323414558, 6119.136988619962, 6243.0, 5695.914561281584, 5703.372629010805, 5710.114932185518, 5738.741756620962, 5772.509336706343, 5222.3733152022005, 6338.239411270585, 6339.4611458025665, 6353.174087368612, 6356.9364306357265, 5849.0, 5870.73581688825, 5942.465178133989, 6014.939850601526, 6032.607880487754, 6129.645238677085, 6148.708771158017, 6168.475679567197, 6182.474489668429, 6250.062490626351, 5957.225560677084, 6033.064874944303, 6153.107799369156, 6171.6297022178815, 6181.35095814933, 6082.908756368658, 6115.724792384264, 6126.693665316717, 6131.877362452726, 6205.720742566596, 4836.806527738921, 4845.200541910615, 4855.441624213261, 4876.073546026315, 4891.51084891508, 4268.274393353137, 4292.635126014324, 4303.935440968388, 4329.718568293051, 4342.218400687906, 5661.943380566142, 5759.020966435716, 5779.768809247583, 5781.971090144522, 5790.652560846363, 6144.969275153685, 6151.3541078186145, 6223.042209977774, 6262.935300998841, 6283.08895210192, 5708.743106560215, 5719.7712091516705, 5725.6850873202375, 5740.39725828782, 5751.396103409165, 5203.14148165556, 5263.0, 5270.209468579744, 5272.103742363807, 5322.0, 6295.0, 6301.873962520724, 6316.305206427198, 6319.3364696706185, 6322.0200868865395, 6092.634441933498, 6251.624902505864, 6260.154879091265, 6281.905771413341, 6282.340354262737, 6150.569460137747, 6189.659568723763, 6268.247810262797, 6290.308066112815, 6335.809925702052, 6112.572119951738, 6169.167162433194, 6189.721462534107, 6194.38056194377, 6210.037444196157, 5992.940070599413, 6182.876342473035, 6199.597026193283, 6216.844578885383, 6249.500039996698, 4884.804607815648, 5850.912236316543, 5924.31870334917, 6013.9097913532205, 6078.635281883101, 5887.293524777054, 5919.467247947533, 5968.492678122235, 6040.879182416415, 6098.908516372347, 5827.3881242470625, 5832.3292821326095, 5845.883082338414, 5854.4730974210825, 5858.238429004202, 5866.560007999332, 5943.0, 5947.672877991752, 5955.761769529344, 6019.638821670714, 5845.970770146122, 5862.208601838868, 5877.500412464989, 5886.323072846506, 5914.112883067574], "value": 5622.818607916071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.02803458546845, 484.08266335294616, 499.7256994663607, 501.32822018495233, 527.0933993531128, 30586.0, 32495.512567311478, 37845.432318515246, 38762.41856372156, 39246.43014279433, 42399.788001059955, 42781.78609106958, 42833.35749963753, 42855.571444285546, 42858.14283714324, 9175.027369115673, 9181.540922953858, 9244.8613270801, 9323.160915517603, 9479.383840050376, 8606.0, 8817.250533704642, 9273.582688778988, 11222.943885280562, 11488.827667584992, 11456.140789440826, 11523.827142592867, 11538.0, 11544.422778861024, 11559.479823407924, 9038.412503187337, 11348.60279890204, 11398.829017564743, 11445.0, 11456.0, 8403.327733781283, 8618.65525378984, 11015.118790496741, 11246.987771100641, 11272.0, 39505.80247098746, 40154.99612509681, 40307.395389069185, 40741.796291018654, 40762.38856417156, 3639.2357604902736, 3656.981715091434, 3680.4479328100733, 3719.1074142205675, 3749.3063783200196, 24785.256442306578, 30475.0, 30677.69322306771, 30925.22686932822, 31224.531632025537, 2651.602259661047, 3161.6996385343355, 3187.6930458511897, 3212.068500134958, 3247.447933851251, 450908.0, 451625.7418712885, 453049.0, 453868.0, 459301.0, 60573.09140362898, 61674.0, 61757.0, 61798.38201617926, 61912.69043654753, 62108.378916211164, 62235.37764622386, 62277.0, 64871.67564162149, 64880.675596622656, 15332.540023799304, 15887.761683574756, 15910.284037218353, 15910.522684319489, 15914.204289785464, 11505.712357191138, 12645.0, 12735.299558524184, 12738.80891786624, 12770.80843787344, 15545.067295962279, 16276.593085172966, 18180.0, 18399.356022539414, 18672.906635466737, 6701.932980670228, 6743.325667433315, 6779.288174741581, 6793.082933803972, 6793.218779840382, 863.4646519158036, 872.8777971084053, 874.226309715906, 915.9587818548181, 928.5078908178541, 28143.859280703466, 28347.71652283492, 29866.0, 30385.696143038287, 30595.2351191222, 1025.9692209233735, 1025.99487002566, 1061.0821639281965, 1066.653337665262, 1067.7384040910026, 13999.300034998209, 14644.169478815149, 14667.559973200821, 14893.627659308604, 14954.401823927139, 585305.0, 588395.0, 591709.0, 592264.0, 592506.0, 52542.0, 52643.0, 52675.209871851956, 52713.0, 52811.73594132005, 1307.4378017452586, 1360.7006458579294, 1398.2659103970498, 1445.0318286748113, 1451.1945870042227, 26470.0, 27102.915883364443, 27484.725152749015, 27683.0, 27824.721752782214, 41980.79009604932, 44326.77836610797, 44379.778101110576, 44420.0, 44581.0, 509669.0, 511846.0, 511867.0, 512461.0, 513127.0, 23574.29277121689, 24165.39586510281, 24466.388340291636, 24482.877585611957, 24547.0, 38798.6120138795, 40716.1856762869, 41439.58560414478, 41642.58357416387, 41864.3720344189, 34057.318853623285, 34166.316673666886, 34567.30865382628, 34622.13444663904, 35158.82420587881, 32230.871919482706, 34258.0, 34373.484397733555, 34966.47550286799, 35092.0, 32941.011769646946, 33423.498647519824, 34084.0, 34515.96452106441, 34547.82726086354, 26997.1900842975, 30546.93085742077, 30643.846780766846, 30736.588953152976, 30942.69057309398, 18657.626847463245, 22372.105115795184, 22547.66178507291, 23222.883885580464, 23311.53376932486], "value": 19130.201003614256, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.2009137886315, 355.7794167616078, 359.8272829042059, 364.5024541500852, 377.98488060477564, 15366.00241968544, 15640.921795391021, 16406.015639061665, 20689.896550517264, 21306.680399794015, 20467.897660511677, 20814.583708325823, 21276.680849787226, 21504.677429838564, 21504.924753762327, 5110.361204849394, 5909.143174239733, 6165.321814600413, 6170.734999325127, 6175.3207147214, 6033.7888173913925, 6121.66330851803, 6171.13604095427, 6171.4445699887, 6173.135760993465, 5836.182934389189, 6016.7593296268315, 6112.388761123896, 6113.633182009072, 6124.356942521038, 5903.409659034062, 6005.7597696092325, 6054.485368743662, 6066.726997285133, 6069.484093852028, 5027.5977921766535, 5228.189630607262, 5288.391834938954, 5309.548688361494, 5317.521423071903, 25916.8704156478, 26434.6034809478, 26447.867760661265, 26667.199984000512, 26723.19830405091, 3029.091272618228, 3070.8311042892633, 3157.0371036833985, 3204.13488358142, 3217.7747557670737, 14838.035527690774, 15119.773203401957, 16064.196790160446, 16188.595285117848, 16417.42539011141, 1804.9729254061199, 1863.245385618813, 1867.8505719542404, 1892.6025534637743, 1895.1661269041715, 104754.09590184843, 128811.0, 133685.0, 135916.0, 139017.30491347477, 11354.772904542027, 11852.703682407837, 11853.88146118545, 11865.466054027589, 11872.821907671392, 15155.166465844484, 15237.6190595236, 15655.608609784618, 15656.060636361855, 15679.372825086868, 7630.580318082559, 8366.121557236524, 8693.087225841322, 8768.342374321825, 8856.601452934634, 7009.719611215596, 8490.193431623986, 8520.914790852012, 8706.912930870736, 8712.564371781513, 4101.5488296287385, 4515.277555591089, 4680.602148817388, 4752.097101550694, 4824.131656301829, 1945.9805401946082, 1960.9901950490441, 1983.7917018712828, 1987.3739771971782, 1990.572027014199, 1014.4775440648073, 1026.6817286641165, 1027.1269420992096, 1036.326387847905, 1063.144168943992, 20681.58636827255, 21298.57402851965, 21630.0, 21713.45716357072, 21741.0, 873.0091346321843, 888.6845169964698, 1015.8527013582989, 1027.7636143686993, 1034.8292531732332, 10686.28034859938, 10979.28634638746, 11090.390028548345, 11138.33170009802, 11146.05258553032, 664818.6759066174, 670546.0, 680750.0, 685114.0, 695802.0, 148139.0, 150169.0, 158321.16788321306, 161276.0, 162367.0, 959.69289827256, 1018.8064267789097, 1050.4642632257603, 1054.667779649408, 1058.730023843915, 18204.635907281514, 18656.533586660444, 18703.1583578742, 18739.437816865517, 19023.0, 53697.463025369245, 56310.0, 56469.71765141148, 58401.80731322071, 61034.0, 69418.26454338682, 77577.0, 87807.12192878245, 87932.0, 89942.0, 8006.759797206093, 8066.435349525477, 8082.676692932217, 8482.491050536988, 8530.10433904431, 7533.924660753322, 11230.213885028297, 11452.713682157679, 12664.6833829155, 13370.799438008633, 10847.67456976292, 11078.889211107786, 11442.37066961325, 11447.370394628042, 11543.018843398406, 9843.310968232156, 11056.004959553678, 11097.889021109686, 11261.7747645045, 11265.271899704583, 11352.716182095513, 11410.486528106088, 11641.301521908712, 11747.353895535827, 11802.704932376759, 8098.797530061795, 8290.212429819157, 8372.748817535483, 8404.789880253042, 8563.100874408346, 8247.587620618826, 8796.736097917073, 10701.946490267499, 11030.0, 11042.668719938414], "value": 9951.159467888325, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.8662133786622, 1453.8255409350872, 1475.9335829887655, 1481.562938933015, 1485.3167542930246, 8086.838263234732, 8524.829503409928, 8534.573271336423, 8551.358648101394, 8567.700130495434, 7530.6987720491115, 7740.148583655793, 7749.53502789833, 8083.312918401943, 8314.70898518552, 2208.7901649343285, 2280.4184932842104, 2281.0191617604423, 2284.132029828663, 2285.611446054175, 2288.4507718147693, 2307.9192228272013, 2321.6865723127416, 2327.313442534448, 2331.5803155432013, 2015.868968517049, 2098.339023207692, 2170.2404158544464, 2275.215050807477, 2309.93070207894, 1918.3765276285123, 2265.229821860567, 2278.931632051033, 2282.5549017941507, 2286.142696488817, 2267.206477732797, 2288.4736510602656, 2289.4161988692777, 2294.105298933417, 2295.7130358705163, 8353.832923341499, 8419.158084191593, 8468.872966905501, 8478.872816907751, 8525.062243153248, 5016.2224855147515, 5160.7419629018395, 5169.948300516984, 5171.637985341028, 5175.585953123741, 3282.573265475473, 3429.6741809528053, 3503.982480087583, 3524.9647503524893, 3543.6633519815578, 1165.353228957928, 1229.2931564350533, 1231.4483750474378, 1251.2867665430708, 1260.8297879786203, 10396.84404733922, 10496.422696751675, 10867.83698244527, 10886.72783180419, 10900.672979810619, 4342.956570434319, 4441.4892287387365, 4473.038296766211, 4487.798049087799, 4497.775111244425, 8420.957895210484, 8608.268297194809, 8719.0, 8726.607302671395, 8730.083341249077, 5123.309307928374, 5129.333186685707, 5137.638525790669, 5137.768800403991, 5146.511081447256, 4650.95349046512, 4807.6153907688, 4819.783109760069, 4856.053069651384, 4874.8537543873745, 3582.6059133495296, 3677.2093999790172, 3686.612905644922, 3697.334479793663, 3728.0307120148955, 1158.1082566423975, 1159.8086315757805, 1165.877582853805, 1167.258790667932, 1168.7954607943575, 788.3062904643991, 794.7417089445953, 884.062893333069, 905.2441211588266, 916.9403988740711, 5438.592105592017, 5525.0, 5803.6807975560905, 5888.174665853599, 5999.100134979788, 1009.6970908727352, 1011.7622358745689, 1013.4932533733133, 1037.1029059863165, 1053.252190944438, 3775.8300876460626, 3807.8476860925807, 3870.748401353903, 3995.780232087263, 4005.8583303758287, 9682.90317096834, 10027.04743049409, 10185.898141018495, 10192.286539942133, 10211.693649190536, 9662.468564229035, 9736.853947190797, 9749.415035097916, 10169.54237059334, 10179.694609161737, 6624.569402988886, 7577.280158384945, 7909.604519773989, 7919.247671471201, 7919.722809701633, 5441.26542916717, 5592.245046918777, 5608.579356548194, 5658.5190258826815, 5691.146328050825, 9181.265498760216, 9301.581428835854, 9377.249820014245, 9386.014468480711, 9400.388974716621, 8535.91464085376, 8918.420302680304, 8946.194842463949, 9512.33413661037, 9527.714168574954, 6710.362515561014, 6877.724891004504, 7152.570845749272, 7155.677994490156, 7240.384567311838, 6166.969165154145, 7877.527348359117, 7966.721164759206, 8079.313258373104, 8079.555624440713, 7110.4667149962925, 7145.678444469907, 7206.89189662145, 7207.8558428832175, 7287.562746235243, 6162.630242185483, 7006.544574602634, 7023.964880175567, 7025.297470253138, 7073.6816843243205, 6407.647579383179, 6434.64609446485, 6451.516136289703, 6483.059956306403, 6486.351364863476, 4324.654027677715, 4997.5002499749735, 5011.5489605935645, 5056.7471626419265, 5064.012517559041, 4840.0, 4936.432310284364, 5035.244713293036, 5104.41299250591, 5108.208227724708], "value": 4386.798062803151, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.371169210961, 348.2356228079367, 350.75972958523414, 356.1665702256719, 366.2309150783354, 4726.929096063562, 4849.17564014118, 4861.756912154396, 5084.745762711864, 5104.617153713472, 3811.752236104658, 4249.723767955081, 4878.146324393235, 4915.287283343913, 4918.754062296889, 3980.7412518186225, 4032.334664780308, 4036.829319497342, 4048.4939382577177, 4054.97972510137, 3484.9651503484893, 3691.280200360931, 3988.8404463821557, 4015.799210039501, 4023.577524359929, 3164.841757912107, 3250.073728987239, 3701.3152566775234, 3986.2027594481074, 4007.0583412897945, 3075.600171977651, 3597.5143355646915, 3816.561095474028, 4025.1547175093265, 4039.333509970851, 3386.2888793353427, 3554.5379100717, 3563.073600863768, 3563.465480177994, 3574.28514297139, 4427.5572442755465, 4973.170658592863, 4979.526944940225, 4980.551750342451, 5013.448520662724, 3614.566252049771, 4147.398627199039, 4385.736855788663, 4430.379746835446, 4433.357163211349, 5276.868078298035, 5324.843438407451, 5343.919841202385, 5361.222622719683, 5396.757145928404, 3177.4598318285716, 3701.130234394915, 3714.220013797106, 3724.4971928789537, 3754.5682246541724, 4948.515445366375, 5006.549410553068, 5154.561862241677, 5579.967705974408, 5771.538276937835, 3165.509346051366, 3351.6815902489225, 3741.5323084614424, 3756.7933763643264, 3760.4735337052834, 3228.806271623663, 3399.9490007649906, 3521.3837578423672, 3796.1838204786104, 3805.6384643458828, 4382.9780851096175, 4397.2304846651705, 4403.801828917706, 4418.469783625986, 4421.2483877740215, 5087.287779710843, 5392.272043274186, 5449.455054494519, 5459.890802183934, 5464.617476776588, 1062.3838173859297, 1230.5816022552376, 1245.2815115141047, 1252.7807633664072, 1254.2599866079056, 748.0649188514357, 794.8211652378169, 811.7402431221952, 852.3677158242022, 854.717923115327, 4187.973946383156, 4762.0, 4886.486918873538, 5070.859056712284, 5141.228815677603, 4702.576768090888, 4857.478328212018, 4865.132219885199, 4894.632902532253, 5521.5306698931045, 3562.201088450316, 4240.98216428055, 4772.926091629356, 4914.410270767459, 5084.805070808357, 2260.6269965455517, 2464.162184857157, 2751.147144385247, 2774.4728501584636, 2789.6373471448583, 4684.391029166255, 4761.35721677576, 4897.881713609287, 4999.825006124768, 5389.7035663038905, 4668.976655116703, 4693.6949098308505, 4815.7351345676325, 4958.925616115761, 5011.924821127686, 5256.185291279858, 5350.839474815762, 5441.5102640762525, 5451.400345962021, 5451.563874889999, 4466.352378905106, 4550.9317360238965, 5222.138347172674, 5286.709230992178, 5299.655522391032, 4405.31717583775, 4475.015496590745, 5048.318477005557, 5204.2714020036465, 5222.3472065991755, 5677.318721753416, 5723.279868085778, 5737.0, 5740.799072032459, 5752.7698892043845, 3528.629493903206, 3623.927521449609, 3813.6949044075855, 3823.502944617182, 3829.9042523937123, 4587.9354290570045, 5373.408925018167, 5567.4967758704115, 5618.325800903917, 5622.353429355677, 4079.265732168238, 5270.41887433776, 5337.386200586827, 5361.51746342831, 5438.401775804579, 4669.4863565007145, 4675.625949924066, 5391.412122181297, 5393.0, 5396.811111611075, 4375.024983011583, 4555.886102847456, 4918.532739389752, 5245.449227831175, 5297.576193904555, 4731.550502702238, 4774.689645173053, 5291.629585929102, 5321.255024296524, 5336.78652853897, 4667.929981050219, 5289.6032797539565, 5322.562908014892, 5331.773692050772, 5332.20016997438], "value": 3849.8076798793054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5068927095668, 724.0984973707734, 740.3817812126874, 783.9372850171986, 831.8086840026792, 14785.334659940301, 15766.369345226192, 15772.76340854888, 15805.367785288603, 15810.367585296603, 14332.713345733106, 14529.346179421926, 14551.563453096423, 14568.927155364208, 14590.781138282935, 683.9076724642184, 704.9541779784298, 711.1146622455025, 846.3102571404316, 854.5385491834402, 862.7239283429274, 865.2299453486402, 867.488181972636, 876.3077169036453, 877.2324216310728, 817.5666896544841, 840.6343240690288, 858.8668756342777, 861.4142383179435, 863.9913600864005, 823.851706692795, 864.0236532717988, 866.9739907802776, 867.9479231246082, 869.2871845087, 532.199040444133, 551.0549407765661, 660.9801705948829, 678.0947435174048, 681.3799442507294, 14931.178785166812, 15378.231088445533, 15454.845451545452, 15464.536063917987, 15477.22613869302, 13820.0, 13959.720805583933, 14016.0, 14054.648633784134, 14054.859451405457, 12453.626391208278, 12710.682232944158, 12825.23048617086, 12859.678508037281, 13115.934420327932, 4346.065595896897, 4427.512973572937, 4580.679352445331, 4638.281066434708, 4642.9071418571775, 97021.5148924258, 97164.0, 97427.0, 99221.50389248008, 103990.0, 10906.72733181661, 11054.447277636247, 11164.609238676607, 11205.71985700364, 11295.209335346612, 22729.88635056814, 22755.31734047981, 22769.658455123186, 22787.202447914242, 22796.4300892479, 7703.499272547267, 7795.84633638094, 7804.726834560764, 7840.41196910234, 7847.607619618996, 4183.811728472165, 4212.452381190487, 4234.576542345802, 4264.722793018444, 4281.957180428156, 3514.279572687593, 3517.891864062812, 3626.0, 3626.5829429615405, 3627.6009638939695, 653.761377097356, 654.4404534123352, 667.9866402672017, 671.6238906212536, 671.9932800672028, 581.340178896955, 632.5319263744888, 652.425865238587, 657.8815813153678, 659.2329735267483, 9019.909800901907, 9050.773730656787, 9353.672621458352, 9783.923768385472, 9929.304948653526, 487.8024400117958, 617.2099712368176, 624.5378419969281, 640.1869625575494, 652.8596351784389, 4737.771367468245, 4834.0, 4879.0, 4885.477946665867, 4888.780004899788, 74622.64198111098, 103428.48285758523, 106691.46654266835, 106763.0, 109997.90002100037, 91560.08439915649, 92377.538112309, 92464.0, 93724.53137734268, 94031.52984235036, 69744.65127674329, 70075.29924700789, 70862.2913770866, 74370.62814685891, 82939.5853020731, 8724.258438032839, 9697.5151242439, 10670.253082284165, 10791.190660700637, 10977.670669879917, 49411.505884942126, 50750.746246270006, 50992.0, 51233.0, 53310.4668953321, 66761.66619166873, 67684.0, 68253.0, 68679.0, 69511.0, 10443.94778026105, 10795.136389089012, 10798.352098874093, 11532.654020379403, 13577.049606527447, 12470.817606918934, 12486.875131248571, 12575.0, 12806.551770687982, 13039.022073344728, 11889.405529723306, 12116.0, 12238.0, 12421.93789031049, 12511.499540018662, 11771.646850594496, 11849.644510664693, 12015.75968480643, 12036.75926481448, 12508.999280057755, 11889.583864564698, 12002.75994480123, 12084.818727719266, 12461.314627695201, 14075.366608502438, 10516.842247366449, 10968.835467467834, 11056.0, 11481.770364592829, 11655.475503602527, 11402.144839137263, 11464.25482343645, 11498.425078746197, 11600.30398176112, 11751.412429378668], "value": 7388.766255342216, "unit": "score"}], "name": "dromaeo_css", "value": 7149.778703209669, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0, 114.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 107.0, 145.0, 110.0, 108.0, 113.0, 142.0, 157.0, 138.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 185.0, 184.0, 181.0, 181.0, 183.0, 195.0, 260.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 59.0, 87.0, 60.0, 60.0, 59.0, 59.0, 73.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 69.0, 65.0, 68.0, 85.0, 69.0, 69.0, 86.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 87.0, 84.0, 89.0, 86.0, 87.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 92.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 86.0, 87.0, 86.0, 85.0, 88.0, 86.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 53.0, 55.0, 55.0, 55.0, 55.0, 56.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 75.0, 75.0, 72.0, 72.0, 71.0, 73.0, 71.0, 98.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 96.0, 106.0, 87.0, 86.0, 87.0, 93.0, 87.0, 92.0, 108.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 141.0, 140.0, 139.0, 140.0, 141.0, 145.0, 207.0, 141.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 170.0, 173.0, 172.0, 173.0, 171.0, 172.0, 171.0, 172.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 69.0, 68.0, 67.0, 68.0, 67.0, 69.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1448.5000000000002, "alertThreshold": 2.0}]} 04:47:58 INFO - Return code: 0 04:47:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:47:58 INFO - # TBPL SUCCESS # 04:47:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:47:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:47:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:47:58 INFO - 2017-01-10 04:47:58.527 screenresolution[1663:10340] starting screenresolution argv=screenresolution get 04:47:58 INFO - 2017-01-10 04:47:58.530 screenresolution[1663:10340] Display 0: 1600x1200x32@60 04:47:58 INFO - 2017-01-10 04:47:58.537 screenresolution[1664:10344] starting screenresolution argv=screenresolution list 04:47:58 INFO - Available Modes on Display 0 04:47:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:47:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:47:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:47:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:47:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:47:58 INFO - Intel Iris: 04:47:58 INFO - Chipset Model: Intel Iris 04:47:58 INFO - Type: GPU 04:47:58 INFO - Bus: Built-In 04:47:58 INFO - VRAM (Dynamic, Max): 1536 MB 04:47:58 INFO - Vendor: Intel (0x8086) 04:47:58 INFO - Device ID: 0x0a2e 04:47:58 INFO - Revision ID: 0x0009 04:47:58 INFO - Displays: 04:47:58 INFO - Display: 04:47:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:47:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:47:58 INFO - Display Serial Number: 1600x1200 60 04:47:58 INFO - Main Display: Yes 04:47:58 INFO - Mirror: Off 04:47:58 INFO - Online: Yes 04:47:58 INFO - Rotation: Supported 04:47:58 INFO - Return code: 0 04:47:58 INFO - Running post-action listener: _resource_record_post_action 04:47:58 INFO - [mozharness: 2017-01-10 12:47:58.773787Z] Finished run-tests step (success) 04:47:58 INFO - Running post-run listener: _resource_record_post_run 04:47:58 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 37821952; Write bytes: 552439808; Read time: 368; Write time: 1831 04:47:58 INFO - TinderboxPrint: CPU usage
53.7% 04:47:58 INFO - TinderboxPrint: I/O read bytes / time
37,821,952 / 368 04:47:58 INFO - TinderboxPrint: I/O write bytes / time
552,439,808 / 1,831 04:47:58 INFO - TinderboxPrint: CPU idle
2,225.0 (46.3%) 04:47:58 INFO - TinderboxPrint: CPU system
962.2 (20.0%) 04:47:58 INFO - TinderboxPrint: CPU user
1,618.5 (33.7%) 04:47:58 INFO - TinderboxPrint: Swap in / out
291,143,680 / 0 04:47:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245942784; Write bytes: 240236544; Read time: 18181; Write time: 510 04:47:58 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35749376; Write bytes: 297764864; Read time: 346; Write time: 1281 04:47:59 INFO - Running post-run listener: _upload_blobber_files 04:47:59 INFO - Blob upload gear active. 04:47:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:47:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:47:59 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:47:59 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:47:59 INFO - (blobuploader) - INFO - Open directory for files ... 04:47:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:47:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:47:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:48:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:00 INFO - (blobuploader) - INFO - Done attempting. 04:48:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:48:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:48:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:01 INFO - (blobuploader) - INFO - Done attempting. 04:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:02 INFO - (blobuploader) - INFO - Done attempting. 04:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:04 INFO - (blobuploader) - INFO - Done attempting. 04:48:04 INFO - (blobuploader) - INFO - Iteration through files over. 04:48:04 INFO - Return code: 0 04:48:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:48:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:48:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a195ba41615d947770dbc7d4703cbbc998b9c842839bda4de45e86ac8f63969e822d6feb7d1cca65c1d76194e39fa8565ff9b60bf01541fa2c86ecf60cd14a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d38ee4a3e089b0f654fb2af01cf4371e7868cfb7c04dd0771094d1d9234281acae1919b5b9b154cbf07e9e2d68ae74582246a3811b625f0e424f483d6f59449", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e730d7df7fa3b2831ac4809c6cc5035389f20461b0886938ab621f0879f6694316658211e09c3981c407b05a209fe3082c11393ed7baa291b0de8d7a7a682b66", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e57871a8a97b9c951041a0daea16a75eeb2f6902d543b567fd76e43c1715e0216b8db2517c6e4326f52080bf559a9b7f14cae989a5a97c774c0fd3f0dae4f51c"} 04:48:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:48:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:48:04 INFO - Contents: 04:48:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a195ba41615d947770dbc7d4703cbbc998b9c842839bda4de45e86ac8f63969e822d6feb7d1cca65c1d76194e39fa8565ff9b60bf01541fa2c86ecf60cd14a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d38ee4a3e089b0f654fb2af01cf4371e7868cfb7c04dd0771094d1d9234281acae1919b5b9b154cbf07e9e2d68ae74582246a3811b625f0e424f483d6f59449", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e730d7df7fa3b2831ac4809c6cc5035389f20461b0886938ab621f0879f6694316658211e09c3981c407b05a209fe3082c11393ed7baa291b0de8d7a7a682b66", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e57871a8a97b9c951041a0daea16a75eeb2f6902d543b567fd76e43c1715e0216b8db2517c6e4326f52080bf559a9b7f14cae989a5a97c774c0fd3f0dae4f51c"} 04:48:04 INFO - Running post-run listener: copy_logs_to_upload_dir 04:48:04 INFO - Copying logs to upload dir... 04:48:04 INFO - mkdir: /builds/slave/test/build/upload/logs 04:48:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.831820 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-10 04:48:04.832483) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 04:48:04.836263) ========= 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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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/Firefox/sha512/a195ba41615d947770dbc7d4703cbbc998b9c842839bda4de45e86ac8f63969e822d6feb7d1cca65c1d76194e39fa8565ff9b60bf01541fa2c86ecf60cd14a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d38ee4a3e089b0f654fb2af01cf4371e7868cfb7c04dd0771094d1d9234281acae1919b5b9b154cbf07e9e2d68ae74582246a3811b625f0e424f483d6f59449", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e730d7df7fa3b2831ac4809c6cc5035389f20461b0886938ab621f0879f6694316658211e09c3981c407b05a209fe3082c11393ed7baa291b0de8d7a7a682b66", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e57871a8a97b9c951041a0daea16a75eeb2f6902d543b567fd76e43c1715e0216b8db2517c6e4326f52080bf559a9b7f14cae989a5a97c774c0fd3f0dae4f51c"} build_url:https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010755 build_url: 'https://queue.taskcluster.net/v1/task/Fg5wNnOATF2NXDuuOInz6g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a195ba41615d947770dbc7d4703cbbc998b9c842839bda4de45e86ac8f63969e822d6feb7d1cca65c1d76194e39fa8565ff9b60bf01541fa2c86ecf60cd14a13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d38ee4a3e089b0f654fb2af01cf4371e7868cfb7c04dd0771094d1d9234281acae1919b5b9b154cbf07e9e2d68ae74582246a3811b625f0e424f483d6f59449", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e730d7df7fa3b2831ac4809c6cc5035389f20461b0886938ab621f0879f6694316658211e09c3981c407b05a209fe3082c11393ed7baa291b0de8d7a7a682b66", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e57871a8a97b9c951041a0daea16a75eeb2f6902d543b567fd76e43c1715e0216b8db2517c6e4326f52080bf559a9b7f14cae989a5a97c774c0fd3f0dae4f51c"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 04:48:04.948625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:48:04.948958) ========= 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.fmx333y9o3/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.zoP3Ycc2Hb/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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.004993 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:48:04.976852) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 04:48:04.977188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 04:48:04.977577) ========= ========= Total master_lag: 0.31 =========