builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0150 starttime: 1490826538.99 results: success (0) buildid: 20170329135122 builduid: d83f97dc45d3461b8cc1f2812f3042af revision: 7230f94c609b6a000ad6696f2db85b2f79e36e61 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:58.986572) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:58.986962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:58.989143) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:59.016485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:59.016839) ========= 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.OOlt9Peuju/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.yv7f0abdiN/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-03-29 15:28:59-- 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.6M=0.001s 2017-03-29 15:28:59 (15.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145812 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:59.189932) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:59.190286) ========= 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.OOlt9Peuju/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.yv7f0abdiN/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.028077 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 15:28:59.239998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-29 15:28:59.240403) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7230f94c609b6a000ad6696f2db85b2f79e36e61 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7230f94c609b6a000ad6696f2db85b2f79e36e61 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OOlt9Peuju/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.yv7f0abdiN/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-03-29 15:28:59,351 truncating revision to first 12 chars 2017-03-29 15:28:59,351 Setting DEBUG logging. 2017-03-29 15:28:59,351 attempt 1/10 2017-03-29 15:28:59,351 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7230f94c609b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 15:29:00,497 unpacking tar archive at: mozilla-beta-7230f94c609b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.410147 ========= master_lag: 1.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-29 15:29:01.726298) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 15:29:01.726600) ========= script_repo_revision: 7230f94c609b6a000ad6696f2db85b2f79e36e61 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 15:29:01.727020) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2017-03-29 15:29:01.727321) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2017-03-29 15:29:03.740555) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-03-29 15:29:03.740967) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OOlt9Peuju/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.yv7f0abdiN/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 15:29:03 INFO - MultiFileLogger online at 20170329 15:29:03 in /builds/slave/test 15:29:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:29:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:03 INFO - {'append_to_log': False, 15:29:03 INFO - 'base_work_dir': '/builds/slave/test', 15:29:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:29:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:03 INFO - 'branch': 'Mozilla-Beta', 15:29:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:03 INFO - 'config_files': ('talos/mac_config.py',), 15:29:03 INFO - 'default_actions': ('clobber', 15:29:03 INFO - 'read-buildbot-config', 15:29:03 INFO - 'download-and-extract', 15:29:03 INFO - 'populate-webroot', 15:29:03 INFO - 'create-virtualenv', 15:29:03 INFO - 'install', 15:29:03 INFO - 'run-tests'), 15:29:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:03 INFO - 'download_minidump_stackwalk': True, 15:29:03 INFO - 'download_symbols': 'ondemand', 15:29:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:03 INFO - 'installer_path': 'installer.exe', 15:29:03 INFO - 'log_level': 'info', 15:29:03 INFO - 'log_name': 'talos', 15:29:03 INFO - 'log_to_console': True, 15:29:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:29:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:29:03 INFO - 'opt_config_files': (), 15:29:03 INFO - 'pip_index': False, 15:29:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:03 INFO - 'cmd': ('bash', 15:29:03 INFO - '-c', 15:29:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:03 INFO - 'enabled': True, 15:29:03 INFO - 'halt_on_failure': False, 15:29:03 INFO - 'name': 'check_screen_resolution'},), 15:29:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:03 INFO - 'cmd': ('bash', 15:29:03 INFO - '-c', 15:29:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:03 INFO - 'enabled': True, 15:29:03 INFO - 'halt_on_failure': False, 15:29:03 INFO - 'name': 'check_screen_resolution'},), 15:29:03 INFO - 'run_cmd_checks_enabled': True, 15:29:03 INFO - 'sps_profile': False, 15:29:03 INFO - 'sps_profile_interval': 0, 15:29:03 INFO - 'suite': 'dromaeojs-e10s', 15:29:03 INFO - 'system_bits': '32', 15:29:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:03 INFO - 'title': 't-yosemite-r7-0150', 15:29:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:03 INFO - 'use_talos_json': True, 15:29:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:03 INFO - 'work_dir': 'build'} 15:29:03 INFO - [mozharness: 2017-03-29 22:29:03.971802Z] Running clobber step. 15:29:03 INFO - Running pre-action listener: _resource_record_pre_action 15:29:03 INFO - Running main action method: clobber 15:29:03 INFO - rmtree: /builds/slave/test/build 15:29:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:06 INFO - Running post-action listener: _resource_record_post_action 15:29:06 INFO - [mozharness: 2017-03-29 22:29:06.592425Z] Finished clobber step (success) 15:29:06 INFO - [mozharness: 2017-03-29 22:29:06.592554Z] Running read-buildbot-config step. 15:29:06 INFO - Running pre-action listener: _resource_record_pre_action 15:29:06 INFO - Running main action method: read_buildbot_config 15:29:06 INFO - Using buildbot properties: 15:29:06 INFO - { 15:29:06 INFO - "project": "", 15:29:06 INFO - "product": "firefox", 15:29:06 INFO - "script_repo_revision": "production", 15:29:06 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:29:06 INFO - "repository": "", 15:29:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:29:06 INFO - "buildid": "20170329135122", 15:29:06 INFO - "pgo_build": "False", 15:29:06 INFO - "basedir": "/builds/slave/test", 15:29:06 INFO - "buildnumber": 8, 15:29:06 INFO - "slavename": "t-yosemite-r7-0150", 15:29:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:29:06 INFO - "platform": "yosemite_r7", 15:29:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:29:06 INFO - "branch": "mozilla-beta", 15:29:06 INFO - "repo_path": "releases/mozilla-beta", 15:29:06 INFO - "revision": "7230f94c609b6a000ad6696f2db85b2f79e36e61", 15:29:06 INFO - "stage_platform": "macosx64", 15:29:06 INFO - "builduid": "d83f97dc45d3461b8cc1f2812f3042af", 15:29:06 INFO - "slavebuilddir": "test" 15:29:06 INFO - } 15:29:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 15:29:06 INFO - Running post-action listener: _resource_record_post_action 15:29:06 INFO - [mozharness: 2017-03-29 22:29:06.595921Z] Finished read-buildbot-config step (success) 15:29:06 INFO - [mozharness: 2017-03-29 22:29:06.596052Z] Running download-and-extract step. 15:29:06 INFO - Running pre-action listener: _resource_record_pre_action 15:29:06 INFO - Running main action method: download_and_extract 15:29:06 INFO - mkdir: /builds/slave/test/build/tests 15:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:06 INFO - trying https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 15:29:06 INFO - Downloading https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 15:29:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 15:29:07 INFO - Downloaded 1165 bytes. 15:29:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 15:29:07 INFO - Using the following test package requirements: 15:29:07 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:29:07 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 15:29:07 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 15:29:07 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:29:07 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 15:29:07 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:29:07 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 15:29:07 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 15:29:07 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 15:29:07 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:29:07 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 15:29:07 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 15:29:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:29:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:29:07 INFO - Fetch https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:29:08 INFO - Expected file size: 16108837 15:29:08 INFO - Obtained file size: 16108837 15:29:15 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:29:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:29:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:29:15 INFO - Fetch https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:29:16 INFO - Expected file size: 16108837 15:29:16 INFO - Obtained file size: 16108837 15:29:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 15:29:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 15:29:22 INFO - Fetch https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 15:29:23 INFO - Expected file size: 14403663 15:29:23 INFO - Obtained file size: 14403663 15:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:24 INFO - trying https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:29:24 INFO - Downloading https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 15:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 15:29:25 INFO - Downloaded 58690527 bytes. 15:29:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:29:25 INFO - mkdir: /builds/slave/test/properties 15:29:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:25 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:25 INFO - Contents: 15:29:25 INFO - build_url:https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:29:26 INFO - Running post-action listener: _resource_record_post_action 15:29:26 INFO - Running post-action listener: set_extra_try_arguments 15:29:26 INFO - [mozharness: 2017-03-29 22:29:26.457292Z] Finished download-and-extract step (success) 15:29:26 INFO - [mozharness: 2017-03-29 22:29:26.457472Z] Running populate-webroot step. 15:29:26 INFO - Running pre-action listener: _resource_record_pre_action 15:29:26 INFO - Running main action method: populate_webroot 15:29:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:26 INFO - '--tppagecycles', 15:29:26 INFO - '7'], 15:29:26 INFO - 'tests': ['tsvgm']}}, 15:29:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['damp', 'tps']}, 15:29:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'tests': ['damp', 'tps']}, 15:29:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['dromaeo_dom']}, 15:29:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['basic_compositor_video']}, 15:29:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['tsvgx', 15:29:26 INFO - 'tsvgr_opacity', 15:29:26 INFO - 'tart', 15:29:26 INFO - 'tscrollx', 15:29:26 INFO - 'cart', 15:29:26 INFO - 'tsvg_static']}, 15:29:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:26 INFO - 'tsvgr_opacity', 15:29:26 INFO - 'tart', 15:29:26 INFO - 'tscrollx', 15:29:26 INFO - 'cart', 15:29:26 INFO - 'tsvg_static']}, 15:29:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-e10s'], 15:29:26 INFO - 'tests': ['tp5o']}, 15:29:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:29:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-e10s', 15:29:26 INFO - '--xperf_path', 15:29:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:26 INFO - 'tests': ['tp5n']}, 15:29:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--xperf_path', 15:29:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:26 INFO - 'tests': ['tp5n']}}, 15:29:26 INFO - 'talos.zip': {'path': '', 15:29:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:26 INFO - Running post-action listener: _resource_record_post_action 15:29:26 INFO - [mozharness: 2017-03-29 22:29:26.476193Z] Finished populate-webroot step (success) 15:29:26 INFO - [mozharness: 2017-03-29 22:29:26.476307Z] Running create-virtualenv step. 15:29:26 INFO - Running pre-action listener: _resource_record_pre_action 15:29:26 INFO - Running main action method: create_virtualenv 15:29:26 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:26 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 15:29:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:26 INFO - Using real prefix '/tools/python27' 15:29:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:27 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:30 INFO - Installing pip.................done. 15:29:30 INFO - Return code: 0 15:29:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:29:30 INFO - Reading from file tmpfile_stdout 15:29:30 INFO - Output received: 15:29:30 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) 15:29:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:30 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')]} 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 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')]} 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:30 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 15:29:30 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 15:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:30 INFO - 'HOME': '/Users/cltbld', 15:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:30 INFO - 'LOGNAME': 'cltbld', 15:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:30 INFO - 'MOZ_NO_REMOTE': '1', 15:29:30 INFO - 'NO_EM_RESTART': '1', 15:29:30 INFO - 'PAGER': '/bin/cat', 15:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:30 INFO - 'PWD': '/builds/slave/test', 15:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:30 INFO - 'SHELL': '/bin/bash', 15:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:30 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:30 INFO - 'USER': 'cltbld', 15:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:30 INFO - 'XPC_FLAGS': '0x0', 15:29:30 INFO - 'XPC_SERVICE_NAME': '0', 15:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:30 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 15:29:30 INFO - Cleaning up... 15:29:30 INFO - Return code: 0 15:29:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:30 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')]} 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 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')]} 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:30 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 15:29:30 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 15:29:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:30 INFO - 'HOME': '/Users/cltbld', 15:29:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:30 INFO - 'LOGNAME': 'cltbld', 15:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:30 INFO - 'MOZ_NO_REMOTE': '1', 15:29:30 INFO - 'NO_EM_RESTART': '1', 15:29:30 INFO - 'PAGER': '/bin/cat', 15:29:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:30 INFO - 'PWD': '/builds/slave/test', 15:29:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:30 INFO - 'SHELL': '/bin/bash', 15:29:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:30 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:30 INFO - 'USER': 'cltbld', 15:29:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:30 INFO - 'XPC_FLAGS': '0x0', 15:29:30 INFO - 'XPC_SERVICE_NAME': '0', 15:29:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:31 INFO - Downloading/unpacking psutil>=3.1.1 15:29:31 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 15:29:31 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 15:29:31 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 15:29:31 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 15:29:31 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 15:29:31 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 15:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:34 INFO - Installing collected packages: psutil 15:29:34 INFO - Running setup.py install for psutil 15:29:34 INFO - building 'psutil._psutil_osx' extension 15:29:34 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 15:29:35 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 15:29:35 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 15:29:35 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 15:29:35 INFO - building 'psutil._psutil_posix' extension 15:29:35 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 15:29:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:29:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:29:35 INFO - ^ 15:29:35 INFO - 1 warning generated. 15:29:35 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 15:29:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:35 INFO - Successfully installed psutil 15:29:35 INFO - Cleaning up... 15:29:35 INFO - Return code: 0 15:29:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:35 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:35 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 15:29:35 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 15:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:35 INFO - 'HOME': '/Users/cltbld', 15:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:35 INFO - 'LOGNAME': 'cltbld', 15:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:35 INFO - 'MOZ_NO_REMOTE': '1', 15:29:35 INFO - 'NO_EM_RESTART': '1', 15:29:35 INFO - 'PAGER': '/bin/cat', 15:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:35 INFO - 'PWD': '/builds/slave/test', 15:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:35 INFO - 'SHELL': '/bin/bash', 15:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:35 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:35 INFO - 'USER': 'cltbld', 15:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:35 INFO - 'XPC_FLAGS': '0x0', 15:29:35 INFO - 'XPC_SERVICE_NAME': '0', 15:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:38 INFO - Installing collected packages: mozsystemmonitor 15:29:38 INFO - Running setup.py install for mozsystemmonitor 15:29:38 INFO - Successfully installed mozsystemmonitor 15:29:38 INFO - Cleaning up... 15:29:39 INFO - Return code: 0 15:29:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29: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', '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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:29: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 jsonschema==2.5.1 15:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:40 INFO - 'HOME': '/Users/cltbld', 15:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:40 INFO - 'LOGNAME': 'cltbld', 15:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:40 INFO - 'MOZ_NO_REMOTE': '1', 15:29:40 INFO - 'NO_EM_RESTART': '1', 15:29:40 INFO - 'PAGER': '/bin/cat', 15:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:40 INFO - 'PWD': '/builds/slave/test', 15:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:40 INFO - 'SHELL': '/bin/bash', 15:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:40 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:40 INFO - 'USER': 'cltbld', 15:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:40 INFO - 'XPC_FLAGS': '0x0', 15:29:40 INFO - 'XPC_SERVICE_NAME': '0', 15:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:40 INFO - Downloading/unpacking jsonschema==2.5.1 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:43 INFO - warning: no files found matching '*.txt' 15:29:43 INFO - no previously-included directories found matching 'build' 15:29:43 INFO - no previously-included directories found matching 'dist' 15:29:43 INFO - no previously-included directories found matching '.git*' 15:29:43 INFO - Installing collected packages: jsonschema, functools32 15:29:43 INFO - Running setup.py install for functools32 15:29:44 INFO - warning: no files found matching '*.txt' 15:29:44 INFO - no previously-included directories found matching 'build' 15:29:44 INFO - no previously-included directories found matching 'dist' 15:29:44 INFO - no previously-included directories found matching '.git*' 15:29:44 INFO - Successfully installed jsonschema functools32 15:29:44 INFO - Cleaning up... 15:29:44 INFO - Return code: 0 15:29:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:29:44 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')]} 15:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:44 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')]} 15:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:44 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:44 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 15:29:44 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 15:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:44 INFO - 'HOME': '/Users/cltbld', 15:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:44 INFO - 'LOGNAME': 'cltbld', 15:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:44 INFO - 'MOZ_NO_REMOTE': '1', 15:29:44 INFO - 'NO_EM_RESTART': '1', 15:29:44 INFO - 'PAGER': '/bin/cat', 15:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:44 INFO - 'PWD': '/builds/slave/test', 15:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:44 INFO - 'SHELL': '/bin/bash', 15:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:44 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:44 INFO - 'USER': 'cltbld', 15:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:44 INFO - 'XPC_FLAGS': '0x0', 15:29:44 INFO - 'XPC_SERVICE_NAME': '0', 15:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:29:44 INFO - Cleaning up... 15:29:44 INFO - Return code: 0 15:29:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29:44 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')]} 15:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:44 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')]} 15:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:44 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:44 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 15:29:44 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 15:29:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:44 INFO - 'HOME': '/Users/cltbld', 15:29:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:44 INFO - 'LOGNAME': 'cltbld', 15:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:44 INFO - 'MOZ_NO_REMOTE': '1', 15:29:44 INFO - 'NO_EM_RESTART': '1', 15:29:44 INFO - 'PAGER': '/bin/cat', 15:29:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:44 INFO - 'PWD': '/builds/slave/test', 15:29:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:44 INFO - 'SHELL': '/bin/bash', 15:29:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:44 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:44 INFO - 'USER': 'cltbld', 15:29:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:44 INFO - 'XPC_FLAGS': '0x0', 15:29:44 INFO - 'XPC_SERVICE_NAME': '0', 15:29:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:44 INFO - Downloading/unpacking blobuploader==1.2.4 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:47 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:29:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:29:47 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 15:29:47 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 15:29:47 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 15:29:47 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 15:29:47 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 15:29:47 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 15:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:29:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:48 INFO - Downloading docopt-0.6.1.tar.gz 15:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:29:48 INFO - Installing collected packages: blobuploader, requests, docopt 15:29:48 INFO - Running setup.py install for blobuploader 15:29:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:29:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:29:48 INFO - Running setup.py install for requests 15:29:49 INFO - Running setup.py install for docopt 15:29:49 INFO - Successfully installed blobuploader requests docopt 15:29:49 INFO - Cleaning up... 15:29:49 INFO - Return code: 0 15:29:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:49 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 15:29:49 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 15:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:49 INFO - 'HOME': '/Users/cltbld', 15:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:49 INFO - 'LOGNAME': 'cltbld', 15:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:49 INFO - 'MOZ_NO_REMOTE': '1', 15:29:49 INFO - 'NO_EM_RESTART': '1', 15:29:49 INFO - 'PAGER': '/bin/cat', 15:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:49 INFO - 'PWD': '/builds/slave/test', 15:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:49 INFO - 'SHELL': '/bin/bash', 15:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:49 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:49 INFO - 'USER': 'cltbld', 15:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:49 INFO - 'XPC_FLAGS': '0x0', 15:29:49 INFO - 'XPC_SERVICE_NAME': '0', 15:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:49 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-WuDlLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:49 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-_eGYPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-OsBckh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-OiKfXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-jhnstN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-u7bh4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-DXKoAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-EDs9df-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-K5W2Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-U5LlFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-0fObpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:50 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-TmFzFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-daJ1AW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-zA34KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-kpK8kO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-YZx0JE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:51 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-EvaK94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:29:51 INFO - Running setup.py install for manifestparser 15:29:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:29:51 INFO - Running setup.py install for mozcrash 15:29:51 INFO - Running setup.py install for mozdebug 15:29:52 INFO - Running setup.py install for mozdevice 15:29:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Running setup.py install for mozfile 15:29:52 INFO - Running setup.py install for mozhttpd 15:29:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Running setup.py install for mozinfo 15:29:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Running setup.py install for mozInstall 15:29:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:29:52 INFO - Running setup.py install for mozleak 15:29:52 INFO - Running setup.py install for mozlog 15:29:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:29:53 INFO - Running setup.py install for moznetwork 15:29:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:29:53 INFO - Running setup.py install for mozprocess 15:29:53 INFO - Running setup.py install for mozprofile 15:29:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:29:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:29:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:29:53 INFO - Running setup.py install for mozrunner 15:29:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:29:53 INFO - Running setup.py install for mozscreenshot 15:29:53 INFO - Running setup.py install for moztest 15:29:54 INFO - Running setup.py install for mozversion 15:29:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:29:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:29:54 INFO - Cleaning up... 15:29:54 INFO - Return code: 0 15:29:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:54 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')]} 15:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:54 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')]} 15:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:54 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:54 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 15:29:54 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 15:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:54 INFO - 'HOME': '/Users/cltbld', 15:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:54 INFO - 'LOGNAME': 'cltbld', 15:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:54 INFO - 'MOZ_NO_REMOTE': '1', 15:29:54 INFO - 'NO_EM_RESTART': '1', 15:29:54 INFO - 'PAGER': '/bin/cat', 15:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:54 INFO - 'PWD': '/builds/slave/test', 15:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:54 INFO - 'SHELL': '/bin/bash', 15:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:54 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:54 INFO - 'USER': 'cltbld', 15:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:54 INFO - 'XPC_FLAGS': '0x0', 15:29:54 INFO - 'XPC_SERVICE_NAME': '0', 15:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:54 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-utLZmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:54 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)) 15:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:54 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-LrHQ4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:54 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)) 15:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:54 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-RsoH85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:54 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)) 15:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:54 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-UF9N1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:54 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)) 15:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:54 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-798ak3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-vcLTGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Igoq33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-o6TbM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-eeG2Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-CCkeOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-5aBwK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-AOZlOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-MrkDfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Y0_QlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:55 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)) 15:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:55 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-odzy44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:56 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-C0yFux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:56 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)) 15:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:56 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-4RWjCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:56 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)) 15:29:56 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)) 15:29:56 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)) 15:29:56 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)) 15:29:56 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)) 15:29:56 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)) 15:29:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:58 INFO - Downloading blessings-1.6.tar.gz 15:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:29:59 INFO - Installing collected packages: blessings 15:29:59 INFO - Running setup.py install for blessings 15:29:59 INFO - Successfully installed blessings 15:29:59 INFO - Cleaning up... 15:29:59 INFO - Return code: 0 15:29:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:59 INFO - Reading from file tmpfile_stdout 15:29:59 INFO - Current package versions: 15:29:59 INFO - blessings == 1.6 15:29:59 INFO - blobuploader == 1.2.4 15:29:59 INFO - docopt == 0.6.1 15:29:59 INFO - functools32 == 3.2.3-2 15:29:59 INFO - jsonschema == 2.5.1 15:29:59 INFO - manifestparser == 1.1 15:29:59 INFO - mozInstall == 1.12 15:29:59 INFO - mozcrash == 1.0 15:29:59 INFO - mozdebug == 0.1 15:29:59 INFO - mozdevice == 0.49 15:29:59 INFO - mozfile == 1.2 15:29:59 INFO - mozhttpd == 0.7 15:29:59 INFO - mozinfo == 0.9 15:29:59 INFO - mozleak == 0.1 15:29:59 INFO - mozlog == 3.4 15:29:59 INFO - moznetwork == 0.27 15:29:59 INFO - mozprocess == 0.24 15:29:59 INFO - mozprofile == 0.28 15:29:59 INFO - mozrunner == 6.13 15:29:59 INFO - mozscreenshot == 0.1 15:29:59 INFO - mozsystemmonitor == 0.3 15:29:59 INFO - moztest == 0.8 15:29:59 INFO - mozversion == 1.4 15:29:59 INFO - psutil == 3.1.1 15:29:59 INFO - requests == 1.2.3 15:29:59 INFO - wsgiref == 0.1.2 15:29:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:59 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 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:59 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 15:29:59 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 15:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:59 INFO - 'HOME': '/Users/cltbld', 15:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:59 INFO - 'LOGNAME': 'cltbld', 15:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:59 INFO - 'MOZ_NO_REMOTE': '1', 15:29:59 INFO - 'NO_EM_RESTART': '1', 15:29:59 INFO - 'PAGER': '/bin/cat', 15:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:59 INFO - 'PWD': '/builds/slave/test', 15:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:59 INFO - 'SHELL': '/bin/bash', 15:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:59 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:59 INFO - 'USER': 'cltbld', 15:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:59 INFO - 'XPC_FLAGS': '0x0', 15:29:59 INFO - 'XPC_SERVICE_NAME': '0', 15:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 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)) 15:29:59 INFO - Cleaning up... 15:29:59 INFO - Return code: 0 15:29:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f173b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f176768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddc9cd2f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, '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.OOlt9Peuju/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.yv7f0abdiN/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 15:29:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:59 INFO - 'HOME': '/Users/cltbld', 15:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:59 INFO - 'LOGNAME': 'cltbld', 15:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:59 INFO - 'MOZ_NO_REMOTE': '1', 15:29:59 INFO - 'NO_EM_RESTART': '1', 15:29:59 INFO - 'PAGER': '/bin/cat', 15:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:59 INFO - 'PWD': '/builds/slave/test', 15:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:59 INFO - 'SHELL': '/bin/bash', 15:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:29:59 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:59 INFO - 'USER': 'cltbld', 15:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:59 INFO - 'XPC_FLAGS': '0x0', 15:29:59 INFO - 'XPC_SERVICE_NAME': '0', 15:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:30:00 INFO - Cleaning up... 15:30:00 INFO - Return code: 0 15:30:00 INFO - Running post-action listener: _resource_record_post_action 15:30:00 INFO - Running post-action listener: _start_resource_monitoring 15:30:00 INFO - Starting resource monitoring. 15:30:00 INFO - [mozharness: 2017-03-29 22:30:00.210647Z] Finished create-virtualenv step (success) 15:30:00 INFO - [mozharness: 2017-03-29 22:30:00.211568Z] Running install step. 15:30:00 INFO - Running pre-action listener: _resource_record_pre_action 15:30:00 INFO - Running main action method: install 15:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:00 INFO - Reading from file tmpfile_stdout 15:30:00 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:00 INFO - Reading from file tmpfile_stdout 15:30:00 INFO - Output received: 15:30:00 INFO - Usage: mozinstall [options] installer 15:30:00 INFO - Options: 15:30:00 INFO - -h, --help show this help message and exit 15:30:00 INFO - -d DEST, --destination=DEST 15:30:00 INFO - Directory to install application into. [default: 15:30:00 INFO - "/builds/slave/test"] 15:30:00 INFO - --app=APP Application being installed. [default: firefox] 15:30:00 INFO - mkdir: /builds/slave/test/build/application 15:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:30:15 INFO - Reading from file tmpfile_stdout 15:30:15 INFO - Output received: 15:30:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:30:15 INFO - Running post-action listener: _resource_record_post_action 15:30:15 INFO - [mozharness: 2017-03-29 22:30:15.512018Z] Finished install step (success) 15:30:15 INFO - [mozharness: 2017-03-29 22:30:15.512137Z] Running run-tests step. 15:30:15 INFO - Running pre-action listener: _resource_record_pre_action 15:30:15 INFO - Running main action method: run_tests 15:30:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:30:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:30:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:30:15 INFO - 2017-03-29 15:30:15.526 screenresolution[1625:7313] starting screenresolution argv=screenresolution get 15:30:15 INFO - 2017-03-29 15:30:15.529 screenresolution[1625:7313] Display 0: 1600x1200x32@60 15:30:15 INFO - 2017-03-29 15:30:15.534 screenresolution[1626:7315] starting screenresolution argv=screenresolution list 15:30:15 INFO - Available Modes on Display 0 15:30:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:30:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:30:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:30:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:15 INFO - Intel Iris: 15:30:15 INFO - Chipset Model: Intel Iris 15:30:15 INFO - Type: GPU 15:30:15 INFO - Bus: Built-In 15:30:15 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:15 INFO - Vendor: Intel (0x8086) 15:30:15 INFO - Device ID: 0x0a2e 15:30:15 INFO - Revision ID: 0x0009 15:30:15 INFO - Displays: 15:30:15 INFO - Display: 15:30:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:15 INFO - Display Serial Number: 1600x1200 60 15:30:15 INFO - Main Display: Yes 15:30:15 INFO - Mirror: Off 15:30:15 INFO - Online: Yes 15:30:15 INFO - Rotation: Supported 15:30:15 INFO - Return code: 0 15:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:15 INFO - Python 2.7.3 15:30:15 INFO - Return code: 0 15:30:15 INFO - grabbing minidump binary from tooltool 15:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:15 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 0x10ef51d30>, '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 0x10ee78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef46be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:15 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 15:30:15 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 15:30:15 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 15:30:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:15 INFO - Return code: 0 15:30:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:30:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:30:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.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-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOlt9Peuju/Render', 15:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:15 INFO - 'HOME': '/Users/cltbld', 15:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:15 INFO - 'LOGNAME': 'cltbld', 15:30:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:15 INFO - 'MOZ_NO_REMOTE': '1', 15:30:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:15 INFO - 'NO_EM_RESTART': '1', 15:30:15 INFO - 'PAGER': '/bin/cat', 15:30:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:15 INFO - 'PWD': '/builds/slave/test', 15:30:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:15 INFO - 'SHELL': '/bin/bash', 15:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yv7f0abdiN/Listeners', 15:30:15 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 15:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:15 INFO - 'USER': 'cltbld', 15:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:15 INFO - 'XPC_FLAGS': '0x0', 15:30:15 INFO - 'XPC_SERVICE_NAME': '0', 15:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:30:16 INFO - mozversion application_buildid: 20170329135122 15:30:16 INFO - mozversion application_changeset: 7230f94c609b6a000ad6696f2db85b2f79e36e61 15:30:16 INFO - mozversion application_display_name: Firefox 15:30:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:16 INFO - mozversion application_name: Firefox 15:30:16 INFO - mozversion application_remotingname: firefox 15:30:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:30:16 INFO - mozversion application_vendor: Mozilla 15:30:16 INFO - mozversion application_version: 53.0 15:30:16 INFO - mozversion platform_buildid: 20170329135122 15:30:16 INFO - mozversion platform_changeset: 7230f94c609b6a000ad6696f2db85b2f79e36e61 15:30:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:30:16 INFO - mozversion platform_version: 53.0 15:30:16 INFO - using testdate: 1490826616 15:30:16 INFO - actual date: 1490826616 15:30:16 INFO - starting webserver on 'localhost:49243' 15:30:16 INFO - SUITE-START | Running 2 tests 15:30:16 INFO - TEST-START | dromaeo_css 15:30:16 INFO - operating with platform_type : mac_ 15:30:16 INFO - Initialising browser for dromaeo_css test... 15:30:16 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpDAbOVb/profile http://localhost:49243/getInfo.html) 15:30:19 INFO - PROCESS | 1636 | 2017-03-29 15:30:19.083 plugin-container[1639:7489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 15:30:19 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:19 INFO - PROCESS | 1636 | 2017-03-29 15:30:19.084 plugin-container[1639:7489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 15:30:19 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:19 INFO - PROCESS | 1636 | 2017-03-29 15:30:19.084 plugin-container[1639:7489] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:19 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 15:30:19 INFO - PROCESS | 1636 | colorDepth:24 15:30:19 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 15:30:19 INFO - PROCESS | 1636 | __metrics 15:30:19 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:30:19 INFO - TEST-INFO | 1636: exit 0 15:30:19 INFO - Browser initialized. 15:30:19 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:19 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpDAbOVb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:22 INFO - PROCESS | 1640 | 2017-03-29 15:30:22.180 plugin-container[1641:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874f, name = 'com.apple.tsm.portname' 15:30:22 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:22 INFO - PROCESS | 1640 | 2017-03-29 15:30:22.180 plugin-container[1641:7622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:30:22 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:22 INFO - PROCESS | 1640 | 2017-03-29 15:30:22.180 plugin-container[1641:7622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:31 INFO - PROCESS | 1640 | RSS: Main: 173064192 15:30:31 INFO - PROCESS | 1640 | 15:33:33 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 15:33:35 INFO - PROCESS | 1640 | RSS: Main: 191782912 15:33:35 INFO - PROCESS | 1640 | 15:36:38 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 15:36:39 INFO - PROCESS | 1640 | RSS: Main: 201097216 15:36:39 INFO - PROCESS | 1640 | 15:39:41 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 15:39:41 INFO - PROCESS | 1640 | RSS: Main: 203829248 15:39:41 INFO - PROCESS | 1640 | 15:42:43 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 15:42:44 INFO - PROCESS | 1640 | RSS: Main: 206921728 15:42:44 INFO - PROCESS | 1640 | 15:45:47 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 15:45:47 INFO - PROCESS | 1640 | RSS: Main: 208093184 15:45:47 INFO - PROCESS | 1640 | 15:48:50 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 15:48:51 INFO - PROCESS | 1640 | RSS: Main: 208441344 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | __start_tp_report 15:48:51 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 15:48:51 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 15:48:51 INFO - PROCESS | 1640 | |i|pagename|runs| 15:48:51 INFO - PROCESS | 1640 | |0;dojo.html;3097.690230976902;3539.415996360599;3562.2341196642733;3563.8752643657467;3574.2136729919407;5112.718800465973;6013.759449622015;6116.633002019882;6123.958926982418;6156.076588511725;6073.301570319403;6099.634021958675;6124.663143527104;6141.846453838656;6186.164867742853;5445.101558242885;5493.450654934514;5502.284702988606;5515.779368825262;5585.748641311151;5535.640183388067;5568.1091025435935;5569.248151499536;5590.18942253373;5621.634593751393;5390.73046347683;5410.594205434608;5560.332760068797;5581.5255703265275;5584.664920104807;5477.534409575171;5497.175423686439;5537.390887002467;5567.220589117527;5637.126245431965;5387.353517577876;5500.147477141049;5506.201600767866;5510.1459273812625;5555.111182210867;5940.435658612425;6017.2779266488305;6100.786472473442;6136.355682653347;6136.69316534176;6240.563160578716;6242.875142497124;6245.188125543696;6294;6324.841878953017;6421.64680942548;6456.128422662928;6456.870862582769;6458.6770661466735;6464.385883341075;6180.412860778219;6215.782447614311;6219.067139928957;6239.87520249597;6241.719122639537;6363.936360636334;6407.199100112486;6470.967645161744;6559.212894452601;6641.236257830315;6199.163112979781;6258.499320054385;6280.497560195265;6281.59169653971;6285.968570157212;6379.489640828722;6389.776357827547;6395.648239346788;6401.455876250478;6415.326390728999;6195.349488303754;6233.625982441068;6246.3441338659695;6338.144350512714;6342.936570634327;6252.187215662026;6262.46769024638;6292.685365731695;6302.747890084345;6359.650219237894;5031.572316353077;5163.277141200235;5166.870828229325;5172.870678233075;5195;4579.885502862388;4643.907121857544;4649.651276154302;4672.995306009224;4702.929456058162;5877.823665290048;5962.195103661037;5971.641701497924;6019.638821670714;6112.533877023262;6362.745490180341;6418.293987661354;6427.839304017343;6431.485481161496;6525.64108974011;5295.920561191585;5897.056470964625;5945.40545945402;5972.924873522721;6036.094585812076;5677.574181936371;5718.027935251017;5730.255066841284;5736.627119237236;5763.971180144073;5775.711214439261;6152.200213972155;6401.903971440339;6404.807855764335;6435.903461448082;6217.813465596039;6231.844203894939;6331.588446750947;6427.453666438218;6511.413972742476;6315.96842015787;6350.7777227796805;6357.650329231752;6423;6423.871522569429;6315.115883776183;6342.90485642725;6344.397282258178;6345;6348.523860710373;6235.906461402992;6349.238091429058;6398.712057957497;6408.1349017881985;6414.198225221848;5891.027980383189;6196.721147548281;6207.782727604512;6211.130441738251;6230.563860529719;5691.373948865538;6215.409536094064;6267.811965641038;6285.371462853678;6288.434040936338;4911.459739428731;6103.816885493442;6200.627962322275;6273.843153921189;6286.717097730704;6139.614800607318;6158.261008678987;6165;6173.074038894202;6185.103160041679;6151.723172457339;6157.26112866441;6175.073738939195;6177;6178.135061091541 15:48:51 INFO - PROCESS | 1640 | |1;ext.html;436.91480161368537;487.30315648622457;495.63818412558834;512.7410657617902;522.1410779268105;35676;43258.567414325844;43356.91607709809;43506.564934350645;43817.561824381744;43829;44213.336799948025;44305.335419968724;44320.55679443204;44336.89157771057;9566;9790.020997900225;9797.755056123582;11384.487698053608;11840.822387664191;11554.768904621902;11670;11674.883251167465;11680.415979201049;11684.474198661039;10098.444585547828;11693;11759.941200293986;11775.882241177607;11787.11596630252;11613.6515904523;11659.417029148593;11666.066714662807;11666.591669291576;11680.423142875772;11669.766604667859;11698.415079246004;11752.706182345424;11755.529778808837;11774.587889423914;39574;39782.80108599438;40078.19843603141;40080.59919400798;40116.79941600273;4006.959930400688;4048.6153815387493;4067.877963661095;4203.810828512694;4337.392765012901;24976.750232497623;29273.70726292731;29492.852535737184;29977.250568735737;31266.37467250665;3311.536384906115;3328.3842489139342;3459.6713312235297;3516.5780106387397;3521.454174602941;415710;463810.9999999966;465531.6723416395;467097;468900;61779.764404711656;62163.37836621666;62188.68905655533;62201.37798621956;62613.37386626076;65207.67396162989;65210.34789652138;65230.347696522425;65518.01722974159;65833.67083164553;15663.765043524356;17374;17577.82422175762;17591.472255832345;17599.472015839547;12379;12691;14152.43390264378;14290.57128286153;14401.423943042371;22368.888155559118;22576;22720.318390448312;22750.08999639996;22790;8774.649014039494;8928.642854285887;8964.68623598171;8965.462072275684;9001.909980900107;772.7874834420579;894.0567701075428;902.255639097747;905.714699869539;911.7994041310901;31759.84120079385;32162.83918580392;32236.516452253236;32355.867544635825;32682.019539413857;1056.2975621211963;1070.4487189097622;1099.8946059210602;1177.4524845946676;1257.346179986402;15122.773158402633;15262.923685381502;15285;15322.463713769965;15648.530544083696;671674;679550;679848;680394;681901;57531.712341438026;58142.4185758137;58152.70923645355;58199.709001455565;58214.708926455096;1344.7512210241136;1379.8960831334891;1415.6743948891813;1416.3767942105442;1506.2920427399106;28734.568981465713;28883.711162888943;29107.981220657177;29472.557911630913;29665.555016674334;50056.99886002333;50571.74714126406;50631.74684126556;50740;50826.983460329844;520487;523015;525074;525671;527007;27465.72534274632;27593.724062759116;28124.859375702992;28163.57754633641;29165.833366665094;46127.76936115298;46665.3000205004;47549.76225118852;47567.524324757695;47836;31871.68128318687;32133.84372620019;32207.838960805046;32220.838895806308;33213;27378.726212738413;31793.523097154022;31852.681473185898;32518.674813251564;32723;36493.635063649024;37157.442638359906;37492.62507375;37606.2478750418;37967.62032379641;27894.44211115807;31836.84081579577;32423.837880810446;32627;32916.341673166884;25150.616716080793;25305;25325.366865828502;25410.491790163724;25739.871300643375 15:48:51 INFO - PROCESS | 1640 | |2;jquery.html;349.4757863205192;395.45822223553733;400.35342921182297;409.08161178155035;434.41571086888143;17569.73645395317;20570;21072.473188170305;21510.56978860424;21822.563548729035;22040.559188816213;22145.55708885821;22389;22868.8856555717;23115.306540803762;5963.105534169877;6137.2942111657285;6260.092286618437;6261.874762504747;6265.404786545294;5888.263967004124;6076.2708474983065;6079.4528492435675;6120.2043734314475;6171.753129874822;6015.458608725214;6025.3372129065765;6042.818715438522;6070.817875463743;6079.665618390986;5826.15520749491;6041.516678665696;6093.878122437571;6107.0534067219205;6198.783042593533;5304.496072873071;6005.069214271794;6058.363871793443;6066.272047354301;6084.6045007074845;13650.726985460335;13711.657208569866;13884.23636699981;13917.23455209963;13983.65040873976;2602.4925139597785;2656.9867150664318;2706.458708258356;2734.5624700048097;2755.476459472694;8587.785305367353;10375.94812025935;10463.947680261626;10854.023137917546;10895.891041089646;1827.634473105371;1837.4671345309844;1850.6113716119496;1887.4243355776482;1891.5743957609566;141888.87166692404;145246;145467;146440.2677986614;147011;11778.11664125194;11981.981531569914;12092.125720513335;12376.690582735324;12386.5664701735;14467.059641123293;15125.54623361301;16299;16339.918300408659;16372.345106195618;8788;9038.457692538468;9174.954125229331;9202.57360109184;9346.439213647203;7939.801504962422;9019.368644194974;9027.50348730813;9030;9228.307876909259;4793.832215872498;4862.124817532807;4923.064617722693;4949.183384741549;4985.52637499437;2045.7009798777585;2088.4883203615213;2090.905909234088;2093.780153083904;2101.117530637136;1018.6892997635792;1029.9433531155858;1049.816282150636;1052.6578861869923;1056.7622284985816;17725.579778517313;19059.90470047641;22302.55394892093;22367.328980130624;22743.31770046901;1055.2718624149313;1070.7055559720984;1097.5609756097465;1107.1474964277622;1110.655696734015;11087.889121108685;11531.71170720722;11715.297082175097;11740.00209982144;11770.352630605228;621693;731471;737199;791262;795541;116169;162251;162482.99999999764;166378.16810915867;167565.3243467574;867.2368315881984;1016.872890888639;1044.5299615173203;1072.1155047086186;1073.3238060022136;17610.64778704393;18042.90978545099;18494.81505184931;21098.367048988555;21127.15491380389;63020;63892.68053659702;64521;65280;66523.66738166472;95414.6146346347;96578.51710741401;96743;97276.51361743145;99648.50175749075;5864.7140864713065;8309.501429914224;8435.114312997048;8788.516631585324;8941.46351218929;9420.481873497023;13169.934150329187;13347.199168049949;13631.522896698569;13995.930020349833;10193.796124077626;11326.32042077478;11377.886221137682;11401.315921044765;11774.705632359259;9866.950665246628;10072.294939354175;10614.787704246026;10995.505202265756;11040.5031773572;11323.377214253294;11587.304761713987;11754.647360579196;11952.34262115592;12018.33899135556;8454.746357609281;8500.937382827147;8812;8945.060768619202;9027.277817774431;10920.068775240748;11571.6528504145;11804.232724872856;11918.642440726791;12027.699307517381 15:48:51 INFO - PROCESS | 1640 | |3;mootools.html;1463.575563086705;1480.4448331875547;1487.620656732533;1492.5074725340637;1492.7462331403667;7841.333486653627;8112;8346.41575089744;8764.992025917021;8780.868286975692;8285;8580.356473264499;8581.957090214555;8675.08911564286;8704.95647521763;2410.337157281744;2445.828791984553;2457.9631305530434;2463.359526523099;2468.2841975826987;2078.667413213886;2439.351160378902;2450.571150048743;2452.362385779701;2459.4712136890566;2292.5529521743265;2303.2629558541275;2319.060780383947;2327.7206735191717;2328.208409140892;2396.724376696667;2401.111588712179;2411.3730430088126;2412.915547955831;2418.6613874057557;2415.40822498489;2419.5040016796515;2424.490856920049;2443.315871555967;2453.9754602453927;7752.341051010614;8770.386072974894;8801.559922003938;8805;8810.559472026373;5261.289725886994;5415.296011518478;5427.592930530226;5431.103867861838;5436.700981446019;3597.1007248187952;3645.434957581579;3665.248624032067;3698.408254679265;3704.370257056307;1261.8044203148527;1263.1473755215193;1295.1646188208554;1322.4710115953599;1324.960251192456;11129;11132.165087618461;11150.553977840795;11162.720931976684;11191.66425007251;4637.281221410687;4850.53919877611;4859.465458799529;4900.607951363882;4928.48250933654;9890.802183956424;9891.307608467338;9899.80200395988;9900.158486528582;9903.207743380512;5499.1476321170285;5504.80733174337;5509.118541033416;5525.254090697705;5533.3083364579425;4494.685372023927;4740.786664600101;4964.081644895706;5017.974910125499;5036.471170527042;3976.3439032559304;4038.697097717683;4087.407325937782;4138.668906487474;4141.109661422808;1312.4815697799506;1319.4590218010762;1323.543377534749;1325.6950901292757;1336.1982810313734;925.8981512033671;927.7707038174445;958.9664361747444;961.1061712607342;1002.4336250018716;5166.741662916915;5794.275715535558;5977.641341519523;6059.4546490815155;6115.541334399849;569.5472099680763;570.4096260370496;576.2508738639835;590.2209084009153;590.8670549126413;3233.708966193054;3837.194189220239;3842.9807850961124;3917.6474117329576;3922.5096862892137;9530.714078577654;10281.897181028244;10288.12550933179;10305.536250868729;10307.330023548595;9995.450250236307;10253.308204146233;10271.281010329354;10279.537420816081;10323.793524129474;8188.017437907489;8241.917580824234;8246.38152138592;8259.463134896212;8274.751757447286;5017.37282839645;5657.981563318642;5700.315962084576;5726.198332233574;5797.594168408171;9406.341556091007;9518.857217141887;9519.76200594963;9544.141027307576;9560.904390956;9482.762930926781;9538.332316737762;9566.091221333962;9688.370255933341;9844.655437059668;6839.1451068616425;7052.400545953651;7162.068931038932;7405.62971851416;7521.135069466863;8103.189681031849;8339.291160251203;8358.874616880874;8381.994160700511;8417.747467575982;7046.99988071102;7251.347378735728;7261.273872612696;7331.670074846538;7380.852382952418;7044.084269044992;7098.219195888551;7119.857602848017;7257.891131632924;7263.782086537412;6588.670566471561;6734.696938637675;6740.157480314961;6743.123393958754;6750.797476075725;5083.2883396323805;5099.209622508517;5103.852254814774;5273.393559740525;5291.3385826771655;4856.927146092741;4963.9007219854675;5281.498257665516;5289.841304760864;5345.1982202669915 15:48:51 INFO - PROCESS | 1640 | |4;prototype.html;351.51841976492204;368.2689860626656;378.32657868993203;382.51993747846467;384.0628865568014;4544.727316361021;4965.999999999995;5045.924311135332;5275.971185618806;5677.347105082911;4774.379330687006;5537.446255374461;5650.785081207539;5668.234788303578;5738.340090889549;3969.3847453644657;4188.97905510472;4354.238008348542;4462.732236065846;4502.324651302298;3995.800209989489;4164.396162556428;4288.97855510722;4349.913001739963;4380.2115619188535;3996.7402118862183;4320.3735458358515;4326;4335.414719012941;4337.197618440583;4199.811008504625;4327.221100201962;4332.6352199057355;4338.110687309094;4343.717658352197;3187.4103290891258;3582.5342705448124;3733.9358282889248;3734.066483379155;3738.775673459601;4520.81916723334;5181.4559471255725;5411.594130440233;5605.831825045215;5631.493165615073;4977.6764510307075;4990.775415106293;5002.649814512985;5023.120953833101;5056;4698.647601429906;5303.920441193347;5490.231367608538;5646;6048.818535443944;3615.5842078161086;3993.0815912340036;4098.692598055128;4159.480064991876;4182.142660754538;5183.3002544656365;6423.8394040148905;6425.678716064178;6432.71052802625;6473.28793832678;4046.838126474908;4063.3701776224734;4125.824140120051;4155.792210389468;4157.958420415817;4214.978925105416;4216.2832318505925;4227.852025179104;4241.172971270573;4250.766207858535;4223.788810559459;4333.653307735373;4943.665210393174;4987.825426110141;4991.925121123186;4963.379577552806;5142.51146141116;5782.277215348082;6098.847528811815;6116.816495505142;1130.7116685245373;1207.4264724255909;1360.5510131709698;1375.9611493322677;1405.5944055944055;862.3790870573177;876.5704804645806;899.7930475990559;919.9172074513326;931.8631269850771;4556.537031855813;5091.618128640367;5210.452902445264;5215.504527069922;5459.099248623933;5101.387833459934;5133.126408860739;5676;6014.7594096236535;6074.878502429927;4499.662525310615;4981.32752078472;5174.737046192608;5305.920411193835;5387.649802762808;2724.8637568121517;2923.956140657892;3117.8976917966315;3124.89062882798;3163.7785355024926;5695.237809662311;5839.854003649943;5860.589758716849;5932.228810254639;6062.636241825505;5441.347038355423;5789.536837053026;6004.979153543908;6080.483158931452;6124.755009799647;5647.0964645656495;5667.4615911488345;6047.546434017554;6050.334463208956;6059.939400605937;5719.056355701429;5942.256378481529;5988.28140623128;6049.758009679564;6145.385461453819;5298.417174110922;6136.6011209271255;6146.678464130234;6155.630662160284;6195.194624698941;5344.786208551614;6029.668368239789;6233.750649974132;6247.219097612799;6330.841728956813;3650.525431693969;3763.8494460222378;4035.798210089425;4135.545090040033;4150.315197992298;5077.974610127074;5219.973900130475;5776.9133462998925;6303.716332764945;6341.397567231105;5318.893622127458;5939.4951429129005;6016.42843929826;6076.204945725016;6097.817065488042;5832.50423713989;6060.666663333559;6093.390660933871;6096.359882212304;6143.600665956698;5619.438056194348;5855.648661080349;5975.880482390241;5981.35137566093;5998.760049597967;5476.123820188751;5540.307461567304;6104.511639068953;6109.022556390956;6155.122687580331;5074.371824145754;5986.521078313637;6005.549583781147;6041.758329666764;6050.969745151246 15:48:51 INFO - PROCESS | 1640 | |5;yui.html;726.4079774983388;784.5880912520929;792.0811858244438;861.7974775927656;863.4215075899147;15399.923000384997;16143.273552690127;16207.675846483064;16353.591160221;16355.672886542277;14775.852241477582;14917.552473425814;14927.32827022784;14940.551783446514;14962.775558366606;690.8100272425098;718.8921661750753;844.4468872888237;870.9216170544665;877.473515890467;877.0659247900965;883.2271762208068;889.1908363389317;890.0076414797519;890.7639475538988;920.2729843423721;945.9952700236489;946.668665966913;947.5404428852002;948.0803620488116;938.3478482454697;940.501534186882;940.9905900941005;942.2320808541017;943.1982814607529;687.2165731066588;693.930606939307;700.6566782276648;702.1223470661673;702.2135208566388;15830.287637056244;15877.444289449813;16099.114548699818;16601.66796664072;16809.831901680947;14830.258487075604;15081.773773393408;15252.0086194397;15266;15327.693446131127;11804.291742495478;11951.163418560704;11965.46155423008;12046.578369757017;12642.810357844639;3796.620337966209;3918.5101862267215;3929.1945151243926;3946.0332218606627;4093.570175131628;107437.46281268622;109620.4518977408;109878;110177.44911275392;113155.43422282918;11592.014678752232;11636.941815291038;11755.823662645067;12086.214396064228;12179.147459677915;21501.46246343854;22517.87410629462;22554.661680074812;24873.005079796967;25090.749092508842;8034.758957231293;8118.634661440249;8153.347732181412;8168;8337.916620833836;4265.3601959705675;4342.305231162999;4531.977340113278;4598.816047358068;4599.08018396322;3230.53157292196;3751.15598990225;3772.2832661794173;3798.506194194737;3830.7893065881085;601.8645804693908;695.1033167214338;723.989140162898;747.6299231880254;775.2944820213579;600.7624293954468;692.7402224165937;728.7886512911302;728.8105092675943;737.4763917618551;10073.899261007442;10593.258471907047;10612.363258204532;10951.570693652535;11178.105751539857;727.1383410658386;731.4660297982397;738.4277185181527;752.9360004399688;764.4763337114094;4435.179491794029;4778.808847646124;4848.7090774553635;4857.9028419431415;4857.975710121426;101964;104897.42653860198;106247.93752062535;113096;114188;97826;98206.05381838557;98455;98779.01220987842;101667;85120.02079927297;85368.57315713506;86135;86654;88341.11658883457;9711.254686129138;9739.561719722487;9749.317547771589;9785.315027947976;9787.804243915225;53849.461505384446;54515.36453906389;55013.72493137509;55155.17267240914;55351.72324138354;58435.53911152255;72319.91520127097;72593;72889.27110728825;74246.14384640427;12255.142140050111;13193.670158246121;13250.801237981244;13342.399592018577;13800.447982081007;13707.725845483234;14063.859361406256;14072.859271407155;15247.077511106787;16468.258928348012;13591.388387522387;14082;14575.635609109857;14695.559133226021;14844.925775371486;14399.328171316247;14429.639259018608;14785.630359241106;14928.178950157411;15385.769213461583;13799.51701690436;14923.477678281643;15011.474598389004;15198.544043678707;15536.378544858078;8809.51547664859;10358.378497290187;10574.2598018138;10589.311694739818;10673.986275733001;10410.479476026017;11134;12062.577809776967;12253.754924901274;12270.877291226974 15:48:51 INFO - PROCESS | 1640 | __end_tp_report 15:48:51 INFO - PROCESS | 1640 | __start_cc_report 15:48:51 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,375 15:48:51 INFO - PROCESS | 1640 | __end_cc_report 15:48:51 INFO - PROCESS | 1640 | __startTimestamp1490827731125__endTimestamp 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | ------- Summary: start ------- 15:48:51 INFO - PROCESS | 1640 | Number of tests: 6 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5934.88 Median:6177.00 stddev:620.78 (10.0%) stddev-sans-first:581.41 15:48:51 INFO - PROCESS | 1640 | Values: 3097.7 3539.4 3562.2 3563.9 3574.2 5112.7 6013.8 6116.6 6124.0 6156.1 6073.3 6099.6 6124.7 6141.8 6186.2 5445.1 5493.5 5502.3 5515.8 5585.7 5535.6 5568.1 5569.2 5590.2 5621.6 5390.7 5410.6 5560.3 5581.5 5584.7 5477.5 5497.2 5537.4 5567.2 5637.1 5387.4 5500.1 5506.2 5510.1 5555.1 5940.4 6017.3 6100.8 6136.4 6136.7 6240.6 6242.9 6245.2 6294.0 6324.8 6421.6 6456.1 6456.9 6458.7 6464.4 6180.4 6215.8 6219.1 6239.9 6241.7 6363.9 6407.2 6471.0 6559.2 6641.2 6199.2 6258.5 6280.5 6281.6 6286.0 6379.5 6389.8 6395.6 6401.5 6415.3 6195.3 6233.6 6246.3 6338.1 6342.9 6252.2 6262.5 6292.7 6302.7 6359.7 5031.6 5163.3 5166.9 5172.9 5195.0 4579.9 4643.9 4649.7 4673.0 4702.9 5877.8 5962.2 5971.6 6019.6 6112.5 6362.7 6418.3 6427.8 6431.5 6525.6 5295.9 5897.1 5945.4 5972.9 6036.1 5677.6 5718.0 5730.3 5736.6 5764.0 5775.7 6152.2 6401.9 6404.8 6435.9 6217.8 6231.8 6331.6 6427.5 6511.4 6316.0 6350.8 6357.7 6423.0 6423.9 6315.1 6342.9 6344.4 6345.0 6348.5 6235.9 6349.2 6398.7 6408.1 6414.2 5891.0 6196.7 6207.8 6211.1 6230.6 5691.4 6215.4 6267.8 6285.4 6288.4 4911.5 6103.8 6200.6 6273.8 6286.7 6139.6 6158.3 6165.0 6173.1 6185.1 6151.7 6157.3 6175.1 6177.0 6178.1 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:69193.20 Median:18113.94 stddev:149844.36 (827.2%) stddev-sans-first:150175.79 15:48:51 INFO - PROCESS | 1640 | Values: 436.9 487.3 495.6 512.7 522.1 35676.0 43258.6 43356.9 43506.6 43817.6 43829.0 44213.3 44305.3 44320.6 44336.9 9566.0 9790.0 9797.8 11384.5 11840.8 11554.8 11670.0 11674.9 11680.4 11684.5 10098.4 11693.0 11759.9 11775.9 11787.1 11613.7 11659.4 11666.1 11666.6 11680.4 11669.8 11698.4 11752.7 11755.5 11774.6 39574.0 39782.8 40078.2 40080.6 40116.8 4007.0 4048.6 4067.9 4203.8 4337.4 24976.8 29273.7 29492.9 29977.3 31266.4 3311.5 3328.4 3459.7 3516.6 3521.5 415710.0 463811.0 465531.7 467097.0 468900.0 61779.8 62163.4 62188.7 62201.4 62613.4 65207.7 65210.3 65230.3 65518.0 65833.7 15663.8 17374.0 17577.8 17591.5 17599.5 12379.0 12691.0 14152.4 14290.6 14401.4 22368.9 22576.0 22720.3 22750.1 22790.0 8774.6 8928.6 8964.7 8965.5 9001.9 772.8 894.1 902.3 905.7 911.8 31759.8 32162.8 32236.5 32355.9 32682.0 1056.3 1070.4 1099.9 1177.5 1257.3 15122.8 15262.9 15285.0 15322.5 15648.5 671674.0 679550.0 679848.0 680394.0 681901.0 57531.7 58142.4 58152.7 58199.7 58214.7 1344.8 1379.9 1415.7 1416.4 1506.3 28734.6 28883.7 29108.0 29472.6 29665.6 50057.0 50571.7 50631.7 50740.0 50827.0 520487.0 523015.0 525074.0 525671.0 527007.0 27465.7 27593.7 28124.9 28163.6 29165.8 46127.8 46665.3 47549.8 47567.5 47836.0 31871.7 32133.8 32207.8 32220.8 33213.0 27378.7 31793.5 31852.7 32518.7 32723.0 36493.6 37157.4 37492.6 37606.2 37967.6 27894.4 31836.8 32423.8 32627.0 32916.3 25150.6 25305.0 25325.4 25410.5 25739.9 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:41164.74 Median:21318.86 stddev:123572.44 (579.6%) stddev-sans-first:123881.09 15:48:51 INFO - PROCESS | 1640 | Values: 349.5 395.5 400.4 409.1 434.4 17569.7 20570.0 21072.5 21510.6 21822.6 22040.6 22145.6 22389.0 22868.9 23115.3 5963.1 6137.3 6260.1 6261.9 6265.4 5888.3 6076.3 6079.5 6120.2 6171.8 6015.5 6025.3 6042.8 6070.8 6079.7 5826.2 6041.5 6093.9 6107.1 6198.8 5304.5 6005.1 6058.4 6066.3 6084.6 13650.7 13711.7 13884.2 13917.2 13983.7 2602.5 2657.0 2706.5 2734.6 2755.5 8587.8 10375.9 10463.9 10854.0 10895.9 1827.6 1837.5 1850.6 1887.4 1891.6 141888.9 145246.0 145467.0 146440.3 147011.0 11778.1 11982.0 12092.1 12376.7 12386.6 14467.1 15125.5 16299.0 16339.9 16372.3 8788.0 9038.5 9175.0 9202.6 9346.4 7939.8 9019.4 9027.5 9030.0 9228.3 4793.8 4862.1 4923.1 4949.2 4985.5 2045.7 2088.5 2090.9 2093.8 2101.1 1018.7 1029.9 1049.8 1052.7 1056.8 17725.6 19059.9 22302.6 22367.3 22743.3 1055.3 1070.7 1097.6 1107.1 1110.7 11087.9 11531.7 11715.3 11740.0 11770.4 621693.0 731471.0 737199.0 791262.0 795541.0 116169.0 162251.0 162483.0 166378.2 167565.3 867.2 1016.9 1044.5 1072.1 1073.3 17610.6 18042.9 18494.8 21098.4 21127.2 63020.0 63892.7 64521.0 65280.0 66523.7 95414.6 96578.5 96743.0 97276.5 99648.5 5864.7 8309.5 8435.1 8788.5 8941.5 9420.5 13169.9 13347.2 13631.5 13995.9 10193.8 11326.3 11377.9 11401.3 11774.7 9867.0 10072.3 10614.8 10995.5 11040.5 11323.4 11587.3 11754.6 11952.3 12018.3 8454.7 8500.9 8812.0 8945.1 9027.3 10920.1 11571.7 11804.2 11918.6 12027.7 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5621.49 Median:5318.27 stddev:3061.31 (57.6%) stddev-sans-first:3053.95 15:48:51 INFO - PROCESS | 1640 | Values: 1463.6 1480.4 1487.6 1492.5 1492.7 7841.3 8112.0 8346.4 8765.0 8780.9 8285.0 8580.4 8582.0 8675.1 8705.0 2410.3 2445.8 2458.0 2463.4 2468.3 2078.7 2439.4 2450.6 2452.4 2459.5 2292.6 2303.3 2319.1 2327.7 2328.2 2396.7 2401.1 2411.4 2412.9 2418.7 2415.4 2419.5 2424.5 2443.3 2454.0 7752.3 8770.4 8801.6 8805.0 8810.6 5261.3 5415.3 5427.6 5431.1 5436.7 3597.1 3645.4 3665.2 3698.4 3704.4 1261.8 1263.1 1295.2 1322.5 1325.0 11129.0 11132.2 11150.6 11162.7 11191.7 4637.3 4850.5 4859.5 4900.6 4928.5 9890.8 9891.3 9899.8 9900.2 9903.2 5499.1 5504.8 5509.1 5525.3 5533.3 4494.7 4740.8 4964.1 5018.0 5036.5 3976.3 4038.7 4087.4 4138.7 4141.1 1312.5 1319.5 1323.5 1325.7 1336.2 925.9 927.8 959.0 961.1 1002.4 5166.7 5794.3 5977.6 6059.5 6115.5 569.5 570.4 576.3 590.2 590.9 3233.7 3837.2 3843.0 3917.6 3922.5 9530.7 10281.9 10288.1 10305.5 10307.3 9995.5 10253.3 10271.3 10279.5 10323.8 8188.0 8241.9 8246.4 8259.5 8274.8 5017.4 5658.0 5700.3 5726.2 5797.6 9406.3 9518.9 9519.8 9544.1 9560.9 9482.8 9538.3 9566.1 9688.4 9844.7 6839.1 7052.4 7162.1 7405.6 7521.1 8103.2 8339.3 8358.9 8382.0 8417.7 7047.0 7251.3 7261.3 7331.7 7380.9 7044.1 7098.2 7119.9 7257.9 7263.8 6588.7 6734.7 6740.2 6743.1 6750.8 5083.3 5099.2 5103.9 5273.4 5291.3 4856.9 4963.9 5281.5 5289.8 5345.2 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4779.89 Median:5217.74 stddev:1470.11 (28.2%) stddev-sans-first:1436.17 15:48:51 INFO - PROCESS | 1640 | Values: 351.5 368.3 378.3 382.5 384.1 4544.7 4966.0 5045.9 5276.0 5677.3 4774.4 5537.4 5650.8 5668.2 5738.3 3969.4 4189.0 4354.2 4462.7 4502.3 3995.8 4164.4 4289.0 4349.9 4380.2 3996.7 4320.4 4326.0 4335.4 4337.2 4199.8 4327.2 4332.6 4338.1 4343.7 3187.4 3582.5 3733.9 3734.1 3738.8 4520.8 5181.5 5411.6 5605.8 5631.5 4977.7 4990.8 5002.6 5023.1 5056.0 4698.6 5303.9 5490.2 5646.0 6048.8 3615.6 3993.1 4098.7 4159.5 4182.1 5183.3 6423.8 6425.7 6432.7 6473.3 4046.8 4063.4 4125.8 4155.8 4158.0 4215.0 4216.3 4227.9 4241.2 4250.8 4223.8 4333.7 4943.7 4987.8 4991.9 4963.4 5142.5 5782.3 6098.8 6116.8 1130.7 1207.4 1360.6 1376.0 1405.6 862.4 876.6 899.8 919.9 931.9 4556.5 5091.6 5210.5 5215.5 5459.1 5101.4 5133.1 5676.0 6014.8 6074.9 4499.7 4981.3 5174.7 5305.9 5387.6 2724.9 2924.0 3117.9 3124.9 3163.8 5695.2 5839.9 5860.6 5932.2 6062.6 5441.3 5789.5 6005.0 6080.5 6124.8 5647.1 5667.5 6047.5 6050.3 6059.9 5719.1 5942.3 5988.3 6049.8 6145.4 5298.4 6136.6 6146.7 6155.6 6195.2 5344.8 6029.7 6233.8 6247.2 6330.8 3650.5 3763.8 4035.8 4135.5 4150.3 5078.0 5220.0 5776.9 6303.7 6341.4 5318.9 5939.5 6016.4 6076.2 6097.8 5832.5 6060.7 6093.4 6096.4 6143.6 5619.4 5855.6 5975.9 5981.4 5998.8 5476.1 5540.3 6104.5 6109.0 6155.1 5074.4 5986.5 6005.5 6041.8 6051.0 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:21787.53 Median:3874.65 stddev:31432.69 (811.2%) stddev-sans-first:31481.09 15:48:51 INFO - PROCESS | 1640 | Values: 726.4 784.6 792.1 861.8 863.4 15399.9 16143.3 16207.7 16353.6 16355.7 14775.9 14917.6 14927.3 14940.6 14962.8 690.8 718.9 844.4 870.9 877.5 877.1 883.2 889.2 890.0 890.8 920.3 946.0 946.7 947.5 948.1 938.3 940.5 941.0 942.2 943.2 687.2 693.9 700.7 702.1 702.2 15830.3 15877.4 16099.1 16601.7 16809.8 14830.3 15081.8 15252.0 15266.0 15327.7 11804.3 11951.2 11965.5 12046.6 12642.8 3796.6 3918.5 3929.2 3946.0 4093.6 107437.5 109620.5 109878.0 110177.4 113155.4 11592.0 11636.9 11755.8 12086.2 12179.1 21501.5 22517.9 22554.7 24873.0 25090.7 8034.8 8118.6 8153.3 8168.0 8337.9 4265.4 4342.3 4532.0 4598.8 4599.1 3230.5 3751.2 3772.3 3798.5 3830.8 601.9 695.1 724.0 747.6 775.3 600.8 692.7 728.8 728.8 737.5 10073.9 10593.3 10612.4 10951.6 11178.1 727.1 731.5 738.4 752.9 764.5 4435.2 4778.8 4848.7 4857.9 4858.0 101964.0 104897.4 106247.9 113096.0 114188.0 97826.0 98206.1 98455.0 98779.0 101667.0 85120.0 85368.6 86135.0 86654.0 88341.1 9711.3 9739.6 9749.3 9785.3 9787.8 53849.5 54515.4 55013.7 55155.2 55351.7 58435.5 72319.9 72593.0 72889.3 74246.1 12255.1 13193.7 13250.8 13342.4 13800.4 13707.7 14063.9 14072.9 15247.1 16468.3 13591.4 14082.0 14575.6 14695.6 14844.9 14399.3 14429.6 14785.6 14928.2 15385.8 13799.5 14923.5 15011.5 15198.5 15536.4 8809.5 10358.4 10574.3 10589.3 10674.0 10410.5 11134.0 12062.6 12253.8 12270.9 15:48:51 INFO - PROCESS | 1640 | -------- Summary: end -------- 15:48:51 INFO - PROCESS | 1640 | 15:48:51 INFO - TEST-INFO | 1640: exit 0 15:48:51 INFO - TEST-OK | dromaeo_css | took 1115604ms 15:48:51 INFO - TEST-START | kraken 15:48:51 INFO - operating with platform_type : mac_ 15:48:51 INFO - Initialising browser for kraken test... 15:48:51 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpdLU_0W/profile http://localhost:49243/getInfo.html) 15:48:54 INFO - PROCESS | 1651 | 2017-03-29 15:48:54.139 plugin-container[1653:9829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 15:48:54 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:54 INFO - PROCESS | 1651 | 2017-03-29 15:48:54.139 plugin-container[1653:9829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:48:54 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:54 INFO - PROCESS | 1651 | 2017-03-29 15:48:54.139 plugin-container[1653:9829] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:48:54 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 15:48:54 INFO - PROCESS | 1651 | colorDepth:24 15:48:54 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 15:48:54 INFO - PROCESS | 1651 | __metrics 15:48:54 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:48:55 INFO - TEST-INFO | 1651: exit 0 15:48:55 INFO - Browser initialized. 15:48:55 INFO - Running cycle 1/1 for kraken test... 15:48:55 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpdLU_0W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:57 INFO - PROCESS | 1654 | 2017-03-29 15:48:57.477 plugin-container[1655:9947] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 15:48:57 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:57 INFO - PROCESS | 1654 | 2017-03-29 15:48:57.477 plugin-container[1655:9947] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:48:57 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:57 INFO - PROCESS | 1654 | 2017-03-29 15:48:57.477 plugin-container[1655:9947] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:49:07 INFO - PROCESS | 1654 | RSS: Main: 172830720 15:49:07 INFO - PROCESS | 1654 | 15:49:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 15:49:11 INFO - PROCESS | 1654 | RSS: Main: 185909248 15:49:11 INFO - PROCESS | 1654 | 15:49:13 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 15:49:14 INFO - PROCESS | 1654 | RSS: Main: 194818048 15:49:14 INFO - PROCESS | 1654 | 15:49:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 15:49:18 INFO - PROCESS | 1654 | RSS: Main: 197885952 15:49:18 INFO - PROCESS | 1654 | 15:49:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 15:49:20 INFO - PROCESS | 1654 | RSS: Main: 198053888 15:49:20 INFO - PROCESS | 1654 | 15:49:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:23 INFO - PROCESS | 1654 | RSS: Main: 197857280 15:49:23 INFO - PROCESS | 1654 | 15:49:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:26 INFO - PROCESS | 1654 | RSS: Main: 202649600 15:49:26 INFO - PROCESS | 1654 | 15:49:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:30 INFO - PROCESS | 1654 | RSS: Main: 203304960 15:49:30 INFO - PROCESS | 1654 | 15:49:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 15:49:34 INFO - PROCESS | 1654 | RSS: Main: 202739712 15:49:34 INFO - PROCESS | 1654 | 15:49:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:36 INFO - PROCESS | 1654 | RSS: Main: 201977856 15:49:36 INFO - PROCESS | 1654 | 15:49:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:38 INFO - PROCESS | 1654 | RSS: Main: 202047488 15:49:38 INFO - PROCESS | 1654 | 15:49:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:41 INFO - PROCESS | 1654 | RSS: Main: 201977856 15:49:41 INFO - PROCESS | 1654 | 15:49:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:45 INFO - PROCESS | 1654 | RSS: Main: 202027008 15:49:45 INFO - PROCESS | 1654 | 15:49:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:48 INFO - PROCESS | 1654 | RSS: Main: 202457088 15:49:48 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 15:49:50 INFO - PROCESS | 1654 | RSS: Main: 204087296 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | __start_tp_report 15:49:50 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 15:49:50 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 15:49:50 INFO - PROCESS | 1654 | |i|pagename|runs| 15:49:50 INFO - PROCESS | 1654 | |0;ai-astar;104;118;118;121;118;129;163;118;118;123 15:49:50 INFO - PROCESS | 1654 | |1;audio-beat-detection;139;136;136;160;110;109;116;137;127;111 15:49:50 INFO - PROCESS | 1654 | |2;audio-dft;198;193;196;197;194;194;197;195;198;195 15:49:50 INFO - PROCESS | 1654 | |3;audio-fft;80;59;59;86;83;51;72;53;64;68 15:49:50 INFO - PROCESS | 1654 | |4;audio-oscillator;86;78;108;97;55;76;105;98;58;96 15:49:50 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;108;90;90;88;91;89;99;91;92;88 15:49:50 INFO - PROCESS | 1654 | |6;imaging-darkroom;141;137;97;97;96;97;98;98;96;97 15:49:50 INFO - PROCESS | 1654 | |7;imaging-desaturate;92;91;91;109;142;149;96;92;130;91 15:49:50 INFO - PROCESS | 1654 | |8;json-parse-financial;58;58;57;60;62;72;86;88;88;61 15:49:50 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;74;69;68;82;74;72;68;68;68;69 15:49:50 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;81;84;81;82;83;83;85;86;84;87 15:49:50 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;138;148;152;129;135;145;152;152;190;180 15:49:50 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;164;224;214;169;164;164;164;187;165;166 15:49:50 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;65;65;66;67;66;65;67;65;65;62 15:49:50 INFO - PROCESS | 1654 | __end_tp_report 15:49:50 INFO - PROCESS | 1654 | __start_cc_report 15:49:50 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,936 15:49:50 INFO - PROCESS | 1654 | __end_cc_report 15:49:50 INFO - PROCESS | 1654 | __startTimestamp1490827790736__endTimestamp 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | ------- Summary: start ------- 15:49:50 INFO - PROCESS | 1654 | Number of tests: 14 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:123.00 Median:119.50 stddev:15.37 (12.9%) stddev-sans-first:14.68 15:49:50 INFO - PROCESS | 1654 | Values: 104.0 118.0 118.0 121.0 118.0 129.0 163.0 118.0 118.0 123.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:136.00 stddev:16.58 (12.2%) stddev-sans-first:17.11 15:49:50 INFO - PROCESS | 1654 | Values: 139.0 136.0 136.0 160.0 110.0 109.0 116.0 137.0 127.0 111.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:195.70 Median:196.50 stddev:1.77 (0.9%) stddev-sans-first:1.67 15:49:50 INFO - PROCESS | 1654 | Values: 198.0 193.0 196.0 197.0 194.0 194.0 197.0 195.0 198.0 195.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:67.50 Median:70.00 stddev:12.47 (17.8%) stddev-sans-first:12.37 15:49:50 INFO - PROCESS | 1654 | Values: 80.0 59.0 59.0 86.0 83.0 51.0 72.0 53.0 64.0 68.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:82.00 stddev:18.61 (22.7%) stddev-sans-first:19.74 15:49:50 INFO - PROCESS | 1654 | Values: 86.0 78.0 108.0 97.0 55.0 76.0 105.0 98.0 58.0 96.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:90.50 stddev:6.26 (6.9%) stddev-sans-first:3.33 15:49:50 INFO - PROCESS | 1654 | Values: 108.0 90.0 90.0 88.0 91.0 89.0 99.0 91.0 92.0 88.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:97.00 stddev:17.75 (18.3%) stddev-sans-first:13.35 15:49:50 INFO - PROCESS | 1654 | Values: 141.0 137.0 97.0 97.0 96.0 97.0 98.0 98.0 96.0 97.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:108.30 Median:91.00 stddev:23.20 (25.5%) stddev-sans-first:23.85 15:49:50 INFO - PROCESS | 1654 | Values: 92.0 91.0 91.0 109.0 142.0 149.0 96.0 92.0 130.0 91.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:69.00 Median:67.00 stddev:13.33 (19.9%) stddev-sans-first:13.53 15:49:50 INFO - PROCESS | 1654 | Values: 58.0 58.0 57.0 60.0 62.0 72.0 86.0 88.0 88.0 61.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:70.50 stddev:4.52 (6.4%) stddev-sans-first:4.68 15:49:50 INFO - PROCESS | 1654 | Values: 74.0 69.0 68.0 82.0 74.0 72.0 68.0 68.0 68.0 69.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:2.01 (2.4%) stddev-sans-first:1.90 15:49:50 INFO - PROCESS | 1654 | Values: 81.0 84.0 81.0 82.0 83.0 83.0 85.0 86.0 84.0 87.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:152.00 stddev:19.17 (12.6%) stddev-sans-first:19.64 15:49:50 INFO - PROCESS | 1654 | Values: 138.0 148.0 152.0 129.0 135.0 145.0 152.0 152.0 190.0 180.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:167.50 stddev:22.78 (13.6%) stddev-sans-first:23.58 15:49:50 INFO - PROCESS | 1654 | Values: 164.0 224.0 214.0 169.0 164.0 164.0 164.0 187.0 165.0 166.0 15:49:50 INFO - PROCESS | 1654 | 15:49:50 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:1.42 (2.2%) stddev-sans-first:1.50 15:49:50 INFO - PROCESS | 1654 | Values: 65.0 65.0 66.0 67.0 66.0 65.0 67.0 65.0 65.0 62.0 15:49:50 INFO - PROCESS | 1654 | -------- Summary: end -------- 15:49:50 INFO - PROCESS | 1654 | 15:49:51 INFO - TEST-INFO | 1654: exit 0 15:49:51 INFO - TEST-OK | kraken | took 59808ms 15:49:51 INFO - SUITE-END | took 1175s 15:49:51 INFO - Completed test suite (00:19:35) 15:49:51 INFO - javascript benchmark 15:49:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7577.002470764845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3097.690230976902, 3539.415996360599, 3562.2341196642733, 3563.8752643657467, 3574.2136729919407, 5112.718800465973, 6013.759449622015, 6116.633002019882, 6123.958926982418, 6156.076588511725, 6073.301570319403, 6099.634021958675, 6124.663143527104, 6141.846453838656, 6186.164867742853, 5445.101558242885, 5493.450654934514, 5502.284702988606, 5515.779368825262, 5585.748641311151, 5535.640183388067, 5568.1091025435935, 5569.248151499536, 5590.18942253373, 5621.634593751393, 5390.73046347683, 5410.594205434608, 5560.332760068797, 5581.5255703265275, 5584.664920104807, 5477.534409575171, 5497.175423686439, 5537.390887002467, 5567.220589117527, 5637.126245431965, 5387.353517577876, 5500.147477141049, 5506.201600767866, 5510.1459273812625, 5555.111182210867, 5940.435658612425, 6017.2779266488305, 6100.786472473442, 6136.355682653347, 6136.69316534176, 6240.563160578716, 6242.875142497124, 6245.188125543696, 6294.0, 6324.841878953017, 6421.64680942548, 6456.128422662928, 6456.870862582769, 6458.6770661466735, 6464.385883341075, 6180.412860778219, 6215.782447614311, 6219.067139928957, 6239.87520249597, 6241.719122639537, 6363.936360636334, 6407.199100112486, 6470.967645161744, 6559.212894452601, 6641.236257830315, 6199.163112979781, 6258.499320054385, 6280.497560195265, 6281.59169653971, 6285.968570157212, 6379.489640828722, 6389.776357827547, 6395.648239346788, 6401.455876250478, 6415.326390728999, 6195.349488303754, 6233.625982441068, 6246.3441338659695, 6338.144350512714, 6342.936570634327, 6252.187215662026, 6262.46769024638, 6292.685365731695, 6302.747890084345, 6359.650219237894, 5031.572316353077, 5163.277141200235, 5166.870828229325, 5172.870678233075, 5195.0, 4579.885502862388, 4643.907121857544, 4649.651276154302, 4672.995306009224, 4702.929456058162, 5877.823665290048, 5962.195103661037, 5971.641701497924, 6019.638821670714, 6112.533877023262, 6362.745490180341, 6418.293987661354, 6427.839304017343, 6431.485481161496, 6525.64108974011, 5295.920561191585, 5897.056470964625, 5945.40545945402, 5972.924873522721, 6036.094585812076, 5677.574181936371, 5718.027935251017, 5730.255066841284, 5736.627119237236, 5763.971180144073, 5775.711214439261, 6152.200213972155, 6401.903971440339, 6404.807855764335, 6435.903461448082, 6217.813465596039, 6231.844203894939, 6331.588446750947, 6427.453666438218, 6511.413972742476, 6315.96842015787, 6350.7777227796805, 6357.650329231752, 6423.0, 6423.871522569429, 6315.115883776183, 6342.90485642725, 6344.397282258178, 6345.0, 6348.523860710373, 6235.906461402992, 6349.238091429058, 6398.712057957497, 6408.1349017881985, 6414.198225221848, 5891.027980383189, 6196.721147548281, 6207.782727604512, 6211.130441738251, 6230.563860529719, 5691.373948865538, 6215.409536094064, 6267.811965641038, 6285.371462853678, 6288.434040936338, 4911.459739428731, 6103.816885493442, 6200.627962322275, 6273.843153921189, 6286.717097730704, 6139.614800607318, 6158.261008678987, 6165.0, 6173.074038894202, 6185.103160041679, 6151.723172457339, 6157.26112866441, 6175.073738939195, 6177.0, 6178.135061091541], "value": 5897.6189032568245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.91480161368537, 487.30315648622457, 495.63818412558834, 512.7410657617902, 522.1410779268105, 35676.0, 43258.567414325844, 43356.91607709809, 43506.564934350645, 43817.561824381744, 43829.0, 44213.336799948025, 44305.335419968724, 44320.55679443204, 44336.89157771057, 9566.0, 9790.020997900225, 9797.755056123582, 11384.487698053608, 11840.822387664191, 11554.768904621902, 11670.0, 11674.883251167465, 11680.415979201049, 11684.474198661039, 10098.444585547828, 11693.0, 11759.941200293986, 11775.882241177607, 11787.11596630252, 11613.6515904523, 11659.417029148593, 11666.066714662807, 11666.591669291576, 11680.423142875772, 11669.766604667859, 11698.415079246004, 11752.706182345424, 11755.529778808837, 11774.587889423914, 39574.0, 39782.80108599438, 40078.19843603141, 40080.59919400798, 40116.79941600273, 4006.959930400688, 4048.6153815387493, 4067.877963661095, 4203.810828512694, 4337.392765012901, 24976.750232497623, 29273.70726292731, 29492.852535737184, 29977.250568735737, 31266.37467250665, 3311.536384906115, 3328.3842489139342, 3459.6713312235297, 3516.5780106387397, 3521.454174602941, 415710.0, 463810.9999999966, 465531.6723416395, 467097.0, 468900.0, 61779.764404711656, 62163.37836621666, 62188.68905655533, 62201.37798621956, 62613.37386626076, 65207.67396162989, 65210.34789652138, 65230.347696522425, 65518.01722974159, 65833.67083164553, 15663.765043524356, 17374.0, 17577.82422175762, 17591.472255832345, 17599.472015839547, 12379.0, 12691.0, 14152.43390264378, 14290.57128286153, 14401.423943042371, 22368.888155559118, 22576.0, 22720.318390448312, 22750.08999639996, 22790.0, 8774.649014039494, 8928.642854285887, 8964.68623598171, 8965.462072275684, 9001.909980900107, 772.7874834420579, 894.0567701075428, 902.255639097747, 905.714699869539, 911.7994041310901, 31759.84120079385, 32162.83918580392, 32236.516452253236, 32355.867544635825, 32682.019539413857, 1056.2975621211963, 1070.4487189097622, 1099.8946059210602, 1177.4524845946676, 1257.346179986402, 15122.773158402633, 15262.923685381502, 15285.0, 15322.463713769965, 15648.530544083696, 671674.0, 679550.0, 679848.0, 680394.0, 681901.0, 57531.712341438026, 58142.4185758137, 58152.70923645355, 58199.709001455565, 58214.708926455096, 1344.7512210241136, 1379.8960831334891, 1415.6743948891813, 1416.3767942105442, 1506.2920427399106, 28734.568981465713, 28883.711162888943, 29107.981220657177, 29472.557911630913, 29665.555016674334, 50056.99886002333, 50571.74714126406, 50631.74684126556, 50740.0, 50826.983460329844, 520487.0, 523015.0, 525074.0, 525671.0, 527007.0, 27465.72534274632, 27593.724062759116, 28124.859375702992, 28163.57754633641, 29165.833366665094, 46127.76936115298, 46665.3000205004, 47549.76225118852, 47567.524324757695, 47836.0, 31871.68128318687, 32133.84372620019, 32207.838960805046, 32220.838895806308, 33213.0, 27378.726212738413, 31793.523097154022, 31852.681473185898, 32518.674813251564, 32723.0, 36493.635063649024, 37157.442638359906, 37492.62507375, 37606.2478750418, 37967.62032379641, 27894.44211115807, 31836.84081579577, 32423.837880810446, 32627.0, 32916.341673166884, 25150.616716080793, 25305.0, 25325.366865828502, 25410.491790163724, 25739.871300643375], "value": 20633.999346329147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.4757863205192, 395.45822223553733, 400.35342921182297, 409.08161178155035, 434.41571086888143, 17569.73645395317, 20570.0, 21072.473188170305, 21510.56978860424, 21822.563548729035, 22040.559188816213, 22145.55708885821, 22389.0, 22868.8856555717, 23115.306540803762, 5963.105534169877, 6137.2942111657285, 6260.092286618437, 6261.874762504747, 6265.404786545294, 5888.263967004124, 6076.2708474983065, 6079.4528492435675, 6120.2043734314475, 6171.753129874822, 6015.458608725214, 6025.3372129065765, 6042.818715438522, 6070.817875463743, 6079.665618390986, 5826.15520749491, 6041.516678665696, 6093.878122437571, 6107.0534067219205, 6198.783042593533, 5304.496072873071, 6005.069214271794, 6058.363871793443, 6066.272047354301, 6084.6045007074845, 13650.726985460335, 13711.657208569866, 13884.23636699981, 13917.23455209963, 13983.65040873976, 2602.4925139597785, 2656.9867150664318, 2706.458708258356, 2734.5624700048097, 2755.476459472694, 8587.785305367353, 10375.94812025935, 10463.947680261626, 10854.023137917546, 10895.891041089646, 1827.634473105371, 1837.4671345309844, 1850.6113716119496, 1887.4243355776482, 1891.5743957609566, 141888.87166692404, 145246.0, 145467.0, 146440.2677986614, 147011.0, 11778.11664125194, 11981.981531569914, 12092.125720513335, 12376.690582735324, 12386.5664701735, 14467.059641123293, 15125.54623361301, 16299.0, 16339.918300408659, 16372.345106195618, 8788.0, 9038.457692538468, 9174.954125229331, 9202.57360109184, 9346.439213647203, 7939.801504962422, 9019.368644194974, 9027.50348730813, 9030.0, 9228.307876909259, 4793.832215872498, 4862.124817532807, 4923.064617722693, 4949.183384741549, 4985.52637499437, 2045.7009798777585, 2088.4883203615213, 2090.905909234088, 2093.780153083904, 2101.117530637136, 1018.6892997635792, 1029.9433531155858, 1049.816282150636, 1052.6578861869923, 1056.7622284985816, 17725.579778517313, 19059.90470047641, 22302.55394892093, 22367.328980130624, 22743.31770046901, 1055.2718624149313, 1070.7055559720984, 1097.5609756097465, 1107.1474964277622, 1110.655696734015, 11087.889121108685, 11531.71170720722, 11715.297082175097, 11740.00209982144, 11770.352630605228, 621693.0, 731471.0, 737199.0, 791262.0, 795541.0, 116169.0, 162251.0, 162482.99999999764, 166378.16810915867, 167565.3243467574, 867.2368315881984, 1016.872890888639, 1044.5299615173203, 1072.1155047086186, 1073.3238060022136, 17610.64778704393, 18042.90978545099, 18494.81505184931, 21098.367048988555, 21127.15491380389, 63020.0, 63892.68053659702, 64521.0, 65280.0, 66523.66738166472, 95414.6146346347, 96578.51710741401, 96743.0, 97276.51361743145, 99648.50175749075, 5864.7140864713065, 8309.501429914224, 8435.114312997048, 8788.516631585324, 8941.46351218929, 9420.481873497023, 13169.934150329187, 13347.199168049949, 13631.522896698569, 13995.930020349833, 10193.796124077626, 11326.32042077478, 11377.886221137682, 11401.315921044765, 11774.705632359259, 9866.950665246628, 10072.294939354175, 10614.787704246026, 10995.505202265756, 11040.5031773572, 11323.377214253294, 11587.304761713987, 11754.647360579196, 11952.34262115592, 12018.33899135556, 8454.746357609281, 8500.937382827147, 8812.0, 8945.060768619202, 9027.277817774431, 10920.068775240748, 11571.6528504145, 11804.232724872856, 11918.642440726791, 12027.699307517381], "value": 10080.726440376333, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.575563086705, 1480.4448331875547, 1487.620656732533, 1492.5074725340637, 1492.7462331403667, 7841.333486653627, 8112.0, 8346.41575089744, 8764.992025917021, 8780.868286975692, 8285.0, 8580.356473264499, 8581.957090214555, 8675.08911564286, 8704.95647521763, 2410.337157281744, 2445.828791984553, 2457.9631305530434, 2463.359526523099, 2468.2841975826987, 2078.667413213886, 2439.351160378902, 2450.571150048743, 2452.362385779701, 2459.4712136890566, 2292.5529521743265, 2303.2629558541275, 2319.060780383947, 2327.7206735191717, 2328.208409140892, 2396.724376696667, 2401.111588712179, 2411.3730430088126, 2412.915547955831, 2418.6613874057557, 2415.40822498489, 2419.5040016796515, 2424.490856920049, 2443.315871555967, 2453.9754602453927, 7752.341051010614, 8770.386072974894, 8801.559922003938, 8805.0, 8810.559472026373, 5261.289725886994, 5415.296011518478, 5427.592930530226, 5431.103867861838, 5436.700981446019, 3597.1007248187952, 3645.434957581579, 3665.248624032067, 3698.408254679265, 3704.370257056307, 1261.8044203148527, 1263.1473755215193, 1295.1646188208554, 1322.4710115953599, 1324.960251192456, 11129.0, 11132.165087618461, 11150.553977840795, 11162.720931976684, 11191.66425007251, 4637.281221410687, 4850.53919877611, 4859.465458799529, 4900.607951363882, 4928.48250933654, 9890.802183956424, 9891.307608467338, 9899.80200395988, 9900.158486528582, 9903.207743380512, 5499.1476321170285, 5504.80733174337, 5509.118541033416, 5525.254090697705, 5533.3083364579425, 4494.685372023927, 4740.786664600101, 4964.081644895706, 5017.974910125499, 5036.471170527042, 3976.3439032559304, 4038.697097717683, 4087.407325937782, 4138.668906487474, 4141.109661422808, 1312.4815697799506, 1319.4590218010762, 1323.543377534749, 1325.6950901292757, 1336.1982810313734, 925.8981512033671, 927.7707038174445, 958.9664361747444, 961.1061712607342, 1002.4336250018716, 5166.741662916915, 5794.275715535558, 5977.641341519523, 6059.4546490815155, 6115.541334399849, 569.5472099680763, 570.4096260370496, 576.2508738639835, 590.2209084009153, 590.8670549126413, 3233.708966193054, 3837.194189220239, 3842.9807850961124, 3917.6474117329576, 3922.5096862892137, 9530.714078577654, 10281.897181028244, 10288.12550933179, 10305.536250868729, 10307.330023548595, 9995.450250236307, 10253.308204146233, 10271.281010329354, 10279.537420816081, 10323.793524129474, 8188.017437907489, 8241.917580824234, 8246.38152138592, 8259.463134896212, 8274.751757447286, 5017.37282839645, 5657.981563318642, 5700.315962084576, 5726.198332233574, 5797.594168408171, 9406.341556091007, 9518.857217141887, 9519.76200594963, 9544.141027307576, 9560.904390956, 9482.762930926781, 9538.332316737762, 9566.091221333962, 9688.370255933341, 9844.655437059668, 6839.1451068616425, 7052.400545953651, 7162.068931038932, 7405.62971851416, 7521.135069466863, 8103.189681031849, 8339.291160251203, 8358.874616880874, 8381.994160700511, 8417.747467575982, 7046.99988071102, 7251.347378735728, 7261.273872612696, 7331.670074846538, 7380.852382952418, 7044.084269044992, 7098.219195888551, 7119.857602848017, 7257.891131632924, 7263.782086537412, 6588.670566471561, 6734.696938637675, 6740.157480314961, 6743.123393958754, 6750.797476075725, 5083.2883396323805, 5099.209622508517, 5103.852254814774, 5273.393559740525, 5291.3385826771655, 4856.927146092741, 4963.9007219854675, 5281.498257665516, 5289.841304760864, 5345.1982202669915], "value": 4530.045379264204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.51841976492204, 368.2689860626656, 378.32657868993203, 382.51993747846467, 384.0628865568014, 4544.727316361021, 4965.999999999995, 5045.924311135332, 5275.971185618806, 5677.347105082911, 4774.379330687006, 5537.446255374461, 5650.785081207539, 5668.234788303578, 5738.340090889549, 3969.3847453644657, 4188.97905510472, 4354.238008348542, 4462.732236065846, 4502.324651302298, 3995.800209989489, 4164.396162556428, 4288.97855510722, 4349.913001739963, 4380.2115619188535, 3996.7402118862183, 4320.3735458358515, 4326.0, 4335.414719012941, 4337.197618440583, 4199.811008504625, 4327.221100201962, 4332.6352199057355, 4338.110687309094, 4343.717658352197, 3187.4103290891258, 3582.5342705448124, 3733.9358282889248, 3734.066483379155, 3738.775673459601, 4520.81916723334, 5181.4559471255725, 5411.594130440233, 5605.831825045215, 5631.493165615073, 4977.6764510307075, 4990.775415106293, 5002.649814512985, 5023.120953833101, 5056.0, 4698.647601429906, 5303.920441193347, 5490.231367608538, 5646.0, 6048.818535443944, 3615.5842078161086, 3993.0815912340036, 4098.692598055128, 4159.480064991876, 4182.142660754538, 5183.3002544656365, 6423.8394040148905, 6425.678716064178, 6432.71052802625, 6473.28793832678, 4046.838126474908, 4063.3701776224734, 4125.824140120051, 4155.792210389468, 4157.958420415817, 4214.978925105416, 4216.2832318505925, 4227.852025179104, 4241.172971270573, 4250.766207858535, 4223.788810559459, 4333.653307735373, 4943.665210393174, 4987.825426110141, 4991.925121123186, 4963.379577552806, 5142.51146141116, 5782.277215348082, 6098.847528811815, 6116.816495505142, 1130.7116685245373, 1207.4264724255909, 1360.5510131709698, 1375.9611493322677, 1405.5944055944055, 862.3790870573177, 876.5704804645806, 899.7930475990559, 919.9172074513326, 931.8631269850771, 4556.537031855813, 5091.618128640367, 5210.452902445264, 5215.504527069922, 5459.099248623933, 5101.387833459934, 5133.126408860739, 5676.0, 6014.7594096236535, 6074.878502429927, 4499.662525310615, 4981.32752078472, 5174.737046192608, 5305.920411193835, 5387.649802762808, 2724.8637568121517, 2923.956140657892, 3117.8976917966315, 3124.89062882798, 3163.7785355024926, 5695.237809662311, 5839.854003649943, 5860.589758716849, 5932.228810254639, 6062.636241825505, 5441.347038355423, 5789.536837053026, 6004.979153543908, 6080.483158931452, 6124.755009799647, 5647.0964645656495, 5667.4615911488345, 6047.546434017554, 6050.334463208956, 6059.939400605937, 5719.056355701429, 5942.256378481529, 5988.28140623128, 6049.758009679564, 6145.385461453819, 5298.417174110922, 6136.6011209271255, 6146.678464130234, 6155.630662160284, 6195.194624698941, 5344.786208551614, 6029.668368239789, 6233.750649974132, 6247.219097612799, 6330.841728956813, 3650.525431693969, 3763.8494460222378, 4035.798210089425, 4135.545090040033, 4150.315197992298, 5077.974610127074, 5219.973900130475, 5776.9133462998925, 6303.716332764945, 6341.397567231105, 5318.893622127458, 5939.4951429129005, 6016.42843929826, 6076.204945725016, 6097.817065488042, 5832.50423713989, 6060.666663333559, 6093.390660933871, 6096.359882212304, 6143.600665956698, 5619.438056194348, 5855.648661080349, 5975.880482390241, 5981.35137566093, 5998.760049597967, 5476.123820188751, 5540.307461567304, 6104.511639068953, 6109.022556390956, 6155.122687580331, 5074.371824145754, 5986.521078313637, 6005.549583781147, 6041.758329666764, 6050.969745151246], "value": 4318.919397748686, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.4079774983388, 784.5880912520929, 792.0811858244438, 861.7974775927656, 863.4215075899147, 15399.923000384997, 16143.273552690127, 16207.675846483064, 16353.591160221, 16355.672886542277, 14775.852241477582, 14917.552473425814, 14927.32827022784, 14940.551783446514, 14962.775558366606, 690.8100272425098, 718.8921661750753, 844.4468872888237, 870.9216170544665, 877.473515890467, 877.0659247900965, 883.2271762208068, 889.1908363389317, 890.0076414797519, 890.7639475538988, 920.2729843423721, 945.9952700236489, 946.668665966913, 947.5404428852002, 948.0803620488116, 938.3478482454697, 940.501534186882, 940.9905900941005, 942.2320808541017, 943.1982814607529, 687.2165731066588, 693.930606939307, 700.6566782276648, 702.1223470661673, 702.2135208566388, 15830.287637056244, 15877.444289449813, 16099.114548699818, 16601.66796664072, 16809.831901680947, 14830.258487075604, 15081.773773393408, 15252.0086194397, 15266.0, 15327.693446131127, 11804.291742495478, 11951.163418560704, 11965.46155423008, 12046.578369757017, 12642.810357844639, 3796.620337966209, 3918.5101862267215, 3929.1945151243926, 3946.0332218606627, 4093.570175131628, 107437.46281268622, 109620.4518977408, 109878.0, 110177.44911275392, 113155.43422282918, 11592.014678752232, 11636.941815291038, 11755.823662645067, 12086.214396064228, 12179.147459677915, 21501.46246343854, 22517.87410629462, 22554.661680074812, 24873.005079796967, 25090.749092508842, 8034.758957231293, 8118.634661440249, 8153.347732181412, 8168.0, 8337.916620833836, 4265.3601959705675, 4342.305231162999, 4531.977340113278, 4598.816047358068, 4599.08018396322, 3230.53157292196, 3751.15598990225, 3772.2832661794173, 3798.506194194737, 3830.7893065881085, 601.8645804693908, 695.1033167214338, 723.989140162898, 747.6299231880254, 775.2944820213579, 600.7624293954468, 692.7402224165937, 728.7886512911302, 728.8105092675943, 737.4763917618551, 10073.899261007442, 10593.258471907047, 10612.363258204532, 10951.570693652535, 11178.105751539857, 727.1383410658386, 731.4660297982397, 738.4277185181527, 752.9360004399688, 764.4763337114094, 4435.179491794029, 4778.808847646124, 4848.7090774553635, 4857.9028419431415, 4857.975710121426, 101964.0, 104897.42653860198, 106247.93752062535, 113096.0, 114188.0, 97826.0, 98206.05381838557, 98455.0, 98779.01220987842, 101667.0, 85120.02079927297, 85368.57315713506, 86135.0, 86654.0, 88341.11658883457, 9711.254686129138, 9739.561719722487, 9749.317547771589, 9785.315027947976, 9787.804243915225, 53849.461505384446, 54515.36453906389, 55013.72493137509, 55155.17267240914, 55351.72324138354, 58435.53911152255, 72319.91520127097, 72593.0, 72889.27110728825, 74246.14384640427, 12255.142140050111, 13193.670158246121, 13250.801237981244, 13342.399592018577, 13800.447982081007, 13707.725845483234, 14063.859361406256, 14072.859271407155, 15247.077511106787, 16468.258928348012, 13591.388387522387, 14082.0, 14575.635609109857, 14695.559133226021, 14844.925775371486, 14399.328171316247, 14429.639259018608, 14785.630359241106, 14928.178950157411, 15385.769213461583, 13799.51701690436, 14923.477678281643, 15011.474598389004, 15198.544043678707, 15536.378544858078, 8809.51547664859, 10358.378497290187, 10574.2598018138, 10589.311694739818, 10673.986275733001, 10410.479476026017, 11134.0, 12062.577809776967, 12253.754924901274, 12270.877291226974], "value": 7883.328176850087, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 118.0, 121.0, 118.0, 129.0, 163.0, 118.0, 118.0, 123.0], "value": 123.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 136.0, 160.0, 110.0, 109.0, 116.0, 137.0, 127.0, 111.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 193.0, 196.0, 197.0, 194.0, 194.0, 197.0, 195.0, 198.0, 195.0], "value": 195.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 86.0, 83.0, 51.0, 72.0, 53.0, 64.0, 68.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 108.0, 97.0, 55.0, 76.0, 105.0, 98.0, 58.0, 96.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 90.0, 90.0, 88.0, 91.0, 89.0, 99.0, 91.0, 92.0, 88.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 97.0, 97.0, 96.0, 97.0, 98.0, 98.0, 96.0, 97.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 109.0, 142.0, 149.0, 96.0, 92.0, 130.0, 91.0], "value": 108.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 60.0, 62.0, 72.0, 86.0, 88.0, 88.0, 61.0], "value": 69.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 69.0, 68.0, 82.0, 74.0, 72.0, 68.0, 68.0, 68.0, 69.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 81.0, 82.0, 83.0, 83.0, 85.0, 86.0, 84.0, 87.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 148.0, 152.0, 129.0, 135.0, 145.0, 152.0, 152.0, 190.0, 180.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 224.0, 214.0, 169.0, 164.0, 164.0, 164.0, 187.0, 165.0, 166.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 67.0, 66.0, 65.0, 67.0, 65.0, 65.0, 62.0], "value": 65.3, "unit": "score"}]}]} 15:49:52 INFO - Return code: 0 15:49:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:52 ERROR - # TBPL SUCCESS # 15:49:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:49:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:49:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:49:52 INFO - 2017-03-29 15:49:52.072 screenresolution[1659:10332] starting screenresolution argv=screenresolution get 15:49:52 INFO - 2017-03-29 15:49:52.074 screenresolution[1659:10332] Display 0: 1600x1200x32@60 15:49:52 INFO - 2017-03-29 15:49:52.080 screenresolution[1660:10335] starting screenresolution argv=screenresolution list 15:49:52 INFO - Available Modes on Display 0 15:49:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:49:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:49:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:49:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:49:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:49:52 INFO - Intel Iris: 15:49:52 INFO - Chipset Model: Intel Iris 15:49:52 INFO - Type: GPU 15:49:52 INFO - Bus: Built-In 15:49:52 INFO - VRAM (Dynamic, Max): 1536 MB 15:49:52 INFO - Vendor: Intel (0x8086) 15:49:52 INFO - Device ID: 0x0a2e 15:49:52 INFO - Revision ID: 0x0009 15:49:52 INFO - Displays: 15:49:52 INFO - Display: 15:49:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:49:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:49:52 INFO - Display Serial Number: 1600x1200 60 15:49:52 INFO - Main Display: Yes 15:49:52 INFO - Mirror: Off 15:49:52 INFO - Online: Yes 15:49:52 INFO - Rotation: Supported 15:49:52 INFO - Return code: 0 15:49:52 INFO - Running post-action listener: _resource_record_post_action 15:49:52 INFO - [mozharness: 2017-03-29 22:49:52.314533Z] Finished run-tests step (success) 15:49:52 INFO - Running post-run listener: _resource_record_post_run 15:49:52 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 38492160; Write bytes: 426560512; Read time: 394; Write time: 1478 15:49:52 INFO - TinderboxPrint: CPU usage
54.5% 15:49:52 INFO - TinderboxPrint: I/O read bytes / time
38,492,160 / 394 15:49:52 INFO - TinderboxPrint: I/O write bytes / time
426,560,512 / 1,478 15:49:52 INFO - TinderboxPrint: CPU idle
2,167.2 (45.5%) 15:49:52 INFO - TinderboxPrint: CPU system
962.1 (20.2%) 15:49:52 INFO - TinderboxPrint: CPU user
1,636.4 (34.3%) 15:49:52 INFO - TinderboxPrint: Swap in / out
160,124,928 / 0 15:49:52 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119411200; Write bytes: 115795456; Read time: 10800; Write time: 312 15:49:52 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 36358144; Write bytes: 299726336; Read time: 370; Write time: 1129 15:49:52 INFO - Running post-run listener: _upload_blobber_files 15:49:52 INFO - Blob upload gear active. 15:49:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:52 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 15:49:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:49:53 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:49:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:54 INFO - (blobuploader) - INFO - Done attempting. 15:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:55 INFO - (blobuploader) - INFO - Done attempting. 15:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:56 INFO - (blobuploader) - INFO - Done attempting. 15:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:59 INFO - (blobuploader) - INFO - Done attempting. 15:49:59 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:59 INFO - Return code: 0 15:49:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11573274fe9fbef8425f50a3f4017afed91988c30657338eeda874b2b4f1cbed6e378128188646751e2f5af6d3794d14499d4ec4bff0f7398fda3b49ff85583f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c165e209433fc24c2038d4a50b5d00a247874ed71ff0a240b5b8f51c518e6effe6bdc14dc5d702152d3d4107a835a33624ac32756e47ce635d1633ba27c79913", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a541e682f54b892efd62b83adf20ffefb936c35d97d4bda75187a8723edee3e0a1eeaee26c4467f385bd2b7a35da2567dad2da7e6e7910721b76a47dab43ef5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97719ca9c0fb29f7ac2e14d48398ee45899a50cc8950d2ea6a8a2f5e4d1c71a56781e89b143d7c68ec4e85cb5bef41c41c4c002963f13b7ef07466e13c832c3e"} 15:49:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:59 INFO - Contents: 15:49:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11573274fe9fbef8425f50a3f4017afed91988c30657338eeda874b2b4f1cbed6e378128188646751e2f5af6d3794d14499d4ec4bff0f7398fda3b49ff85583f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c165e209433fc24c2038d4a50b5d00a247874ed71ff0a240b5b8f51c518e6effe6bdc14dc5d702152d3d4107a835a33624ac32756e47ce635d1633ba27c79913", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a541e682f54b892efd62b83adf20ffefb936c35d97d4bda75187a8723edee3e0a1eeaee26c4467f385bd2b7a35da2567dad2da7e6e7910721b76a47dab43ef5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97719ca9c0fb29f7ac2e14d48398ee45899a50cc8950d2ea6a8a2f5e4d1c71a56781e89b143d7c68ec4e85cb5bef41c41c4c002963f13b7ef07466e13c832c3e"} 15:49:59 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:59 INFO - Copying logs to upload dir... 15:49:59 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.385368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-03-29 15:49:59.165935) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 15:49:59.169081) ========= 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.OOlt9Peuju/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.yv7f0abdiN/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11573274fe9fbef8425f50a3f4017afed91988c30657338eeda874b2b4f1cbed6e378128188646751e2f5af6d3794d14499d4ec4bff0f7398fda3b49ff85583f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c165e209433fc24c2038d4a50b5d00a247874ed71ff0a240b5b8f51c518e6effe6bdc14dc5d702152d3d4107a835a33624ac32756e47ce635d1633ba27c79913", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a541e682f54b892efd62b83adf20ffefb936c35d97d4bda75187a8723edee3e0a1eeaee26c4467f385bd2b7a35da2567dad2da7e6e7910721b76a47dab43ef5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97719ca9c0fb29f7ac2e14d48398ee45899a50cc8950d2ea6a8a2f5e4d1c71a56781e89b143d7c68ec4e85cb5bef41c41c4c002963f13b7ef07466e13c832c3e"} build_url:https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/D8ypfo99Tp-XInZynU7iFA/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11573274fe9fbef8425f50a3f4017afed91988c30657338eeda874b2b4f1cbed6e378128188646751e2f5af6d3794d14499d4ec4bff0f7398fda3b49ff85583f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c165e209433fc24c2038d4a50b5d00a247874ed71ff0a240b5b8f51c518e6effe6bdc14dc5d702152d3d4107a835a33624ac32756e47ce635d1633ba27c79913", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a541e682f54b892efd62b83adf20ffefb936c35d97d4bda75187a8723edee3e0a1eeaee26c4467f385bd2b7a35da2567dad2da7e6e7910721b76a47dab43ef5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97719ca9c0fb29f7ac2e14d48398ee45899a50cc8950d2ea6a8a2f5e4d1c71a56781e89b143d7c68ec4e85cb5bef41c41c4c002963f13b7ef07466e13c832c3e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 15:49:59.202870) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 15:49:59.203205) ========= 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.OOlt9Peuju/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.yv7f0abdiN/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.004859 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 15:49:59.252644) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 15:49:59.252972) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 15:49:59.253297) ========= ========= Total master_lag: 1.23 =========