builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0328 starttime: 1490616745.62 results: success (0) buildid: 20170327034016 builduid: b8b71349da0f40b69409c39cdc589a42 revision: 6188b6cab49e2bfa036b24d8112cda5f5f9416f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.617391) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.617843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.618126) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.645389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.645967) ========= 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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-27 05:12:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 171M=0s 2017-03-27 05:12:25 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104781 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.776788) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.777168) ========= 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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.025449 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:25.825462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 05:12:25.825820) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6188b6cab49e2bfa036b24d8112cda5f5f9416f0 --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 6188b6cab49e2bfa036b24d8112cda5f5f9416f0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-27 05:12:25,931 truncating revision to first 12 chars 2017-03-27 05:12:25,932 Setting DEBUG logging. 2017-03-27 05:12:25,932 attempt 1/10 2017-03-27 05:12:25,932 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6188b6cab49e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 05:12:27,335 unpacking tar archive at: mozilla-beta-6188b6cab49e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.680053 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 05:12:27.576222) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:27.576581) ========= script_repo_revision: 6188b6cab49e2bfa036b24d8112cda5f5f9416f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:27.576983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:27.577249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:12:27.597506) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-27 05:12:27.598239) ========= /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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:12:27 INFO - MultiFileLogger online at 20170327 05:12:27 in /builds/slave/test 05:12:27 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 05:12:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:27 INFO - {'append_to_log': False, 05:12:27 INFO - 'base_work_dir': '/builds/slave/test', 05:12:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:12:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:27 INFO - 'branch': 'Mozilla-Beta', 05:12:27 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:27 INFO - 'config_files': ('talos/mac_config.py',), 05:12:27 INFO - 'default_actions': ('clobber', 05:12:27 INFO - 'read-buildbot-config', 05:12:27 INFO - 'download-and-extract', 05:12:27 INFO - 'populate-webroot', 05:12:27 INFO - 'create-virtualenv', 05:12:27 INFO - 'install', 05:12:27 INFO - 'run-tests'), 05:12:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:27 INFO - 'download_minidump_stackwalk': True, 05:12:27 INFO - 'download_symbols': 'ondemand', 05:12:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:27 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:27 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:27 INFO - 'installer_path': 'installer.exe', 05:12:27 INFO - 'log_level': 'info', 05:12:27 INFO - 'log_name': 'talos', 05:12:27 INFO - 'log_to_console': True, 05:12:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:12:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:12:27 INFO - 'opt_config_files': (), 05:12:27 INFO - 'pip_index': False, 05:12:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:27 INFO - 'cmd': ('bash', 05:12:27 INFO - '-c', 05:12:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:27 INFO - 'enabled': True, 05:12:27 INFO - 'halt_on_failure': False, 05:12:27 INFO - 'name': 'check_screen_resolution'},), 05:12:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:27 INFO - 'cmd': ('bash', 05:12:27 INFO - '-c', 05:12:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:27 INFO - 'enabled': True, 05:12:27 INFO - 'halt_on_failure': False, 05:12:27 INFO - 'name': 'check_screen_resolution'},), 05:12:27 INFO - 'run_cmd_checks_enabled': True, 05:12:27 INFO - 'sps_profile': False, 05:12:27 INFO - 'sps_profile_interval': 0, 05:12:27 INFO - 'suite': 'dromaeojs-e10s', 05:12:27 INFO - 'system_bits': '32', 05:12:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:27 INFO - 'title': 't-yosemite-r7-0328', 05:12:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:27 INFO - 'use_talos_json': True, 05:12:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:27 INFO - 'work_dir': 'build'} 05:12:27 INFO - [mozharness: 2017-03-27 12:12:27.814123Z] Running clobber step. 05:12:27 INFO - Running pre-action listener: _resource_record_pre_action 05:12:27 INFO - Running main action method: clobber 05:12:27 INFO - rmtree: /builds/slave/test/build 05:12:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:30 INFO - Running post-action listener: _resource_record_post_action 05:12:30 INFO - [mozharness: 2017-03-27 12:12:30.331149Z] Finished clobber step (success) 05:12:30 INFO - [mozharness: 2017-03-27 12:12:30.331272Z] Running read-buildbot-config step. 05:12:30 INFO - Running pre-action listener: _resource_record_pre_action 05:12:30 INFO - Running main action method: read_buildbot_config 05:12:30 INFO - Using buildbot properties: 05:12:30 INFO - { 05:12:30 INFO - "project": "", 05:12:30 INFO - "product": "firefox", 05:12:30 INFO - "script_repo_revision": "production", 05:12:30 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:12:30 INFO - "repository": "", 05:12:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:12:30 INFO - "buildid": "20170327034016", 05:12:30 INFO - "pgo_build": "False", 05:12:30 INFO - "basedir": "/builds/slave/test", 05:12:30 INFO - "buildnumber": 9, 05:12:30 INFO - "slavename": "t-yosemite-r7-0328", 05:12:30 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:12:30 INFO - "platform": "yosemite_r7", 05:12:30 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:12:30 INFO - "branch": "mozilla-beta", 05:12:30 INFO - "repo_path": "releases/mozilla-beta", 05:12:30 INFO - "revision": "6188b6cab49e2bfa036b24d8112cda5f5f9416f0", 05:12:30 INFO - "stage_platform": "macosx64", 05:12:30 INFO - "builduid": "b8b71349da0f40b69409c39cdc589a42", 05:12:30 INFO - "slavebuilddir": "test" 05:12:30 INFO - } 05:12:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 05:12:30 INFO - Running post-action listener: _resource_record_post_action 05:12:30 INFO - [mozharness: 2017-03-27 12:12:30.334635Z] Finished read-buildbot-config step (success) 05:12:30 INFO - [mozharness: 2017-03-27 12:12:30.334737Z] Running download-and-extract step. 05:12:30 INFO - Running pre-action listener: _resource_record_pre_action 05:12:30 INFO - Running main action method: download_and_extract 05:12:30 INFO - mkdir: /builds/slave/test/build/tests 05:12: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')]} 05:12:30 INFO - trying https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 05:12:30 INFO - Downloading https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/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 05:12:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/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 05:12:31 INFO - Downloaded 1165 bytes. 05:12:31 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:12:31 INFO - Using the following test package requirements: 05:12:31 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:12:31 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 05:12:31 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 05:12:31 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:12:31 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 05:12:31 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:12:31 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 05:12:31 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 05:12:31 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 05:12:31 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:12:31 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 05:12:31 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 05:12:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:12:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:12:31 INFO - Fetch https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:12:32 INFO - Expected file size: 16108482 05:12:32 INFO - Obtained file size: 16108482 05:12:39 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 05:12:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:12:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:12:39 INFO - Fetch https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:12:40 INFO - Expected file size: 16108482 05:12:40 INFO - Obtained file size: 16108482 05:12:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 05:12:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 05:12:46 INFO - Fetch https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 05:12:47 INFO - Expected file size: 14403663 05:12:47 INFO - Obtained file size: 14403663 05:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:47 INFO - trying https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:12:47 INFO - Downloading https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 05:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/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 05:12:49 INFO - Downloaded 58675568 bytes. 05:12:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:12:49 INFO - mkdir: /builds/slave/test/properties 05:12:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:12:49 INFO - Writing to file /builds/slave/test/properties/build_url 05:12:49 INFO - Contents: 05:12:49 INFO - build_url:https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:12:50 INFO - Running post-action listener: _resource_record_post_action 05:12:50 INFO - Running post-action listener: set_extra_try_arguments 05:12:50 INFO - [mozharness: 2017-03-27 12:12:50.644170Z] Finished download-and-extract step (success) 05:12:50 INFO - [mozharness: 2017-03-27 12:12:50.644321Z] Running populate-webroot step. 05:12:50 INFO - Running pre-action listener: _resource_record_pre_action 05:12:50 INFO - Running main action method: populate_webroot 05:12:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:12:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:12:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:12:50 INFO - '--tppagecycles', 05:12:50 INFO - '7'], 05:12:50 INFO - 'tests': ['tsvgm']}}, 05:12:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:12:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:12:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:12:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:12:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['damp', 'tps']}, 05:12:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'tests': ['damp', 'tps']}, 05:12:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['dromaeo_dom']}, 05:12:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:12:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['basic_compositor_video']}, 05:12:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:12:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['a11yr', 05:12:50 INFO - 'ts_paint', 05:12:50 INFO - 'tpaint', 05:12:50 INFO - 'sessionrestore', 05:12:50 INFO - 'sessionrestore_no_auto_restore', 05:12:50 INFO - 'tabpaint']}, 05:12:50 INFO - 'other-e10s': {'tests': ['a11yr', 05:12:50 INFO - 'ts_paint', 05:12:50 INFO - 'tpaint', 05:12:50 INFO - 'sessionrestore', 05:12:50 INFO - 'sessionrestore_no_auto_restore', 05:12:50 INFO - 'tabpaint']}, 05:12:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:12:50 INFO - 'ts_paint', 05:12:50 INFO - 'tpaint', 05:12:50 INFO - 'sessionrestore', 05:12:50 INFO - 'sessionrestore_no_auto_restore', 05:12:50 INFO - 'tabpaint']}, 05:12:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:12:50 INFO - 'ts_paint', 05:12:50 INFO - 'tpaint', 05:12:50 INFO - 'sessionrestore', 05:12:50 INFO - 'sessionrestore_no_auto_restore', 05:12:50 INFO - 'tabpaint']}, 05:12:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['a11yr', 05:12:50 INFO - 'ts_paint', 05:12:50 INFO - 'tpaint', 05:12:50 INFO - 'sessionrestore', 05:12:50 INFO - 'sessionrestore_no_auto_restore', 05:12:50 INFO - 'tabpaint']}, 05:12:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['a11yr', 05:12:50 INFO - 'ts_paint', 05:12:50 INFO - 'tpaint', 05:12:50 INFO - 'sessionrestore', 05:12:50 INFO - 'sessionrestore_no_auto_restore', 05:12:50 INFO - 'tabpaint']}, 05:12:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['tsvgx', 05:12:50 INFO - 'tsvgr_opacity', 05:12:50 INFO - 'tart', 05:12:50 INFO - 'tscrollx', 05:12:50 INFO - 'cart', 05:12:50 INFO - 'tsvg_static']}, 05:12:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:12:50 INFO - 'tsvgr_opacity', 05:12:50 INFO - 'tart', 05:12:50 INFO - 'tscrollx', 05:12:50 INFO - 'cart', 05:12:50 INFO - 'tsvg_static']}, 05:12:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'talos_options': ['--disable-e10s'], 05:12:50 INFO - 'tests': ['tp5o']}, 05:12:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:12:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'talos_options': ['--disable-e10s', 05:12:50 INFO - '--xperf_path', 05:12:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:50 INFO - 'tests': ['tp5n']}, 05:12:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:12:50 INFO - 'talos_options': ['--xperf_path', 05:12:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:50 INFO - 'tests': ['tp5n']}}, 05:12:50 INFO - 'talos.zip': {'path': '', 05:12:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:12:50 INFO - Running post-action listener: _resource_record_post_action 05:12:50 INFO - [mozharness: 2017-03-27 12:12:50.662888Z] Finished populate-webroot step (success) 05:12:50 INFO - [mozharness: 2017-03-27 12:12:50.662994Z] Running create-virtualenv step. 05:12:50 INFO - Running pre-action listener: _resource_record_pre_action 05:12:50 INFO - Running main action method: create_virtualenv 05:12:50 INFO - Creating virtualenv /builds/slave/test/build/venv 05:12:50 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 05:12:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:12:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:12:50 INFO - Using real prefix '/tools/python27' 05:12:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:12:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:12:54 INFO - Installing pip.................done. 05:12:54 INFO - Return code: 0 05:12:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:12:54 INFO - Reading from file tmpfile_stdout 05:12:54 INFO - Output received: 05:12:54 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) 05:12:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:54 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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:12:54 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 05:12:54 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 05:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:54 INFO - 'HOME': '/Users/cltbld', 05:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:54 INFO - 'LOGNAME': 'cltbld', 05:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:54 INFO - 'MOZ_NO_REMOTE': '1', 05:12:54 INFO - 'NO_EM_RESTART': '1', 05:12:54 INFO - 'PAGER': '/bin/cat', 05:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:54 INFO - 'PWD': '/builds/slave/test', 05:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:54 INFO - 'SHELL': '/bin/bash', 05:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:12:54 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:54 INFO - 'USER': 'cltbld', 05:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:54 INFO - 'XPC_FLAGS': '0x0', 05:12:54 INFO - 'XPC_SERVICE_NAME': '0', 05:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:54 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 05:12:54 INFO - Cleaning up... 05:12:54 INFO - Return code: 0 05:12:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:54 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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:12:54 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 05:12:54 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 05:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:54 INFO - 'HOME': '/Users/cltbld', 05:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:54 INFO - 'LOGNAME': 'cltbld', 05:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:54 INFO - 'MOZ_NO_REMOTE': '1', 05:12:54 INFO - 'NO_EM_RESTART': '1', 05:12:54 INFO - 'PAGER': '/bin/cat', 05:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:54 INFO - 'PWD': '/builds/slave/test', 05:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:54 INFO - 'SHELL': '/bin/bash', 05:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:12:54 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:54 INFO - 'USER': 'cltbld', 05:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:54 INFO - 'XPC_FLAGS': '0x0', 05:12:54 INFO - 'XPC_SERVICE_NAME': '0', 05:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:55 INFO - Downloading/unpacking psutil>=3.1.1 05:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:58 INFO - Installing collected packages: psutil 05:12:58 INFO - Running setup.py install for psutil 05:12:58 INFO - building 'psutil._psutil_osx' extension 05:12:58 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 05:12:59 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 05:12:59 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 05:12:59 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 05:12:59 INFO - building 'psutil._psutil_posix' extension 05:12:59 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 05:12:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:12:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:12:59 INFO - ^ 05:12:59 INFO - 1 warning generated. 05:12:59 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 05:12:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:59 INFO - Successfully installed psutil 05:12:59 INFO - Cleaning up... 05:12:59 INFO - Return code: 0 05:12:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:12: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:12: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 mozsystemmonitor==0.3 05:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:00 INFO - 'HOME': '/Users/cltbld', 05:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:00 INFO - 'LOGNAME': 'cltbld', 05:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:00 INFO - 'MOZ_NO_REMOTE': '1', 05:13:00 INFO - 'NO_EM_RESTART': '1', 05:13:00 INFO - 'PAGER': '/bin/cat', 05:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:00 INFO - 'PWD': '/builds/slave/test', 05:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:00 INFO - 'SHELL': '/bin/bash', 05:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:00 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:00 INFO - 'USER': 'cltbld', 05:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:00 INFO - 'XPC_FLAGS': '0x0', 05:13:00 INFO - 'XPC_SERVICE_NAME': '0', 05:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:13:00 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 05:13:00 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 05:13:00 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 05:13:00 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 05:13:00 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 05:13:00 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 05:13:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:13:02 INFO - Installing collected packages: mozsystemmonitor 05:13:02 INFO - Running setup.py install for mozsystemmonitor 05:13:03 INFO - Successfully installed mozsystemmonitor 05:13:03 INFO - Cleaning up... 05:13:03 INFO - Return code: 0 05:13:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:03 INFO - 'HOME': '/Users/cltbld', 05:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:03 INFO - 'LOGNAME': 'cltbld', 05:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:03 INFO - 'MOZ_NO_REMOTE': '1', 05:13:03 INFO - 'NO_EM_RESTART': '1', 05:13:03 INFO - 'PAGER': '/bin/cat', 05:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:03 INFO - 'PWD': '/builds/slave/test', 05:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:03 INFO - 'SHELL': '/bin/bash', 05:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:03 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:03 INFO - 'USER': 'cltbld', 05:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:03 INFO - 'XPC_FLAGS': '0x0', 05:13:03 INFO - 'XPC_SERVICE_NAME': '0', 05:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:03 INFO - Downloading/unpacking jsonschema==2.5.1 05:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:13:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:13:05 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 05:13:05 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 05:13:05 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 05:13:05 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 05:13:05 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 05:13:05 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 05:13:06 INFO - Downloading functools32-3.2.3-2.tar.gz 05:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:13:06 INFO - warning: no files found matching '*.txt' 05:13:06 INFO - no previously-included directories found matching 'build' 05:13:06 INFO - no previously-included directories found matching 'dist' 05:13:06 INFO - no previously-included directories found matching '.git*' 05:13:06 INFO - Installing collected packages: jsonschema, functools32 05:13:06 INFO - Running setup.py install for functools32 05:13:06 INFO - warning: no files found matching '*.txt' 05:13:06 INFO - no previously-included directories found matching 'build' 05:13:06 INFO - no previously-included directories found matching 'dist' 05:13:06 INFO - no previously-included directories found matching '.git*' 05:13:06 INFO - Successfully installed jsonschema functools32 05:13:06 INFO - Cleaning up... 05:13:06 INFO - Return code: 0 05:13:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:13:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:06 INFO - 'HOME': '/Users/cltbld', 05:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:06 INFO - 'LOGNAME': 'cltbld', 05:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:06 INFO - 'MOZ_NO_REMOTE': '1', 05:13:06 INFO - 'NO_EM_RESTART': '1', 05:13:06 INFO - 'PAGER': '/bin/cat', 05:13:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:06 INFO - 'PWD': '/builds/slave/test', 05:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:06 INFO - 'SHELL': '/bin/bash', 05:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:06 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:06 INFO - 'USER': 'cltbld', 05:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:06 INFO - 'XPC_FLAGS': '0x0', 05:13:06 INFO - 'XPC_SERVICE_NAME': '0', 05:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:13:06 INFO - Cleaning up... 05:13:06 INFO - Return code: 0 05:13:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:13:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:06 INFO - 'HOME': '/Users/cltbld', 05:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:06 INFO - 'LOGNAME': 'cltbld', 05:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:06 INFO - 'MOZ_NO_REMOTE': '1', 05:13:06 INFO - 'NO_EM_RESTART': '1', 05:13:06 INFO - 'PAGER': '/bin/cat', 05:13:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:06 INFO - 'PWD': '/builds/slave/test', 05:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:06 INFO - 'SHELL': '/bin/bash', 05:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:06 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:06 INFO - 'USER': 'cltbld', 05:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:06 INFO - 'XPC_FLAGS': '0x0', 05:13:06 INFO - 'XPC_SERVICE_NAME': '0', 05:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:07 INFO - Downloading/unpacking blobuploader==1.2.4 05:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:09 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:11 INFO - Downloading docopt-0.6.1.tar.gz 05:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:11 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:11 INFO - Running setup.py install for blobuploader 05:13:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:11 INFO - Running setup.py install for requests 05:13:11 INFO - Running setup.py install for docopt 05:13:12 INFO - Successfully installed blobuploader requests docopt 05:13:12 INFO - Cleaning up... 05:13:12 INFO - Return code: 0 05:13:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:12 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')]} 05:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:12 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')]} 05:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:12 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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:12 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 05:13:12 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 05:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:12 INFO - 'HOME': '/Users/cltbld', 05:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:12 INFO - 'LOGNAME': 'cltbld', 05:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:12 INFO - 'MOZ_NO_REMOTE': '1', 05:13:12 INFO - 'NO_EM_RESTART': '1', 05:13:12 INFO - 'PAGER': '/bin/cat', 05:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:12 INFO - 'PWD': '/builds/slave/test', 05:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:12 INFO - 'SHELL': '/bin/bash', 05:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:12 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:12 INFO - 'USER': 'cltbld', 05:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:12 INFO - 'XPC_FLAGS': '0x0', 05:13:12 INFO - 'XPC_SERVICE_NAME': '0', 05:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:12 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-pyPmWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:12 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Iy4KkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:12 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-rbv6T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:12 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-XB10Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:12 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-OGiD0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-9LxRXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-6YJGxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-_p9ZRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-VpWJDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-8RWGk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-xVaqnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-mhKSI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-KjckuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-u6T7tM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:13 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-9XcQZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:14 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-eNgG02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:14 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-4eAthd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:13:14 INFO - Running setup.py install for manifestparser 05:13:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for mozcrash 05:13:14 INFO - Running setup.py install for mozdebug 05:13:14 INFO - Running setup.py install for mozdevice 05:13:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for mozfile 05:13:14 INFO - Running setup.py install for mozhttpd 05:13:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for mozinfo 05:13:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for mozInstall 05:13:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for mozleak 05:13:15 INFO - Running setup.py install for mozlog 05:13:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for moznetwork 05:13:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for mozprocess 05:13:16 INFO - Running setup.py install for mozprofile 05:13:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:13:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:13:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:13:16 INFO - Running setup.py install for mozrunner 05:13:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:13:16 INFO - Running setup.py install for mozscreenshot 05:13:16 INFO - Running setup.py install for moztest 05:13:16 INFO - Running setup.py install for mozversion 05:13:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:13:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:13:16 INFO - Cleaning up... 05:13:16 INFO - Return code: 0 05:13:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:16 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')]} 05:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:16 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')]} 05:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:16 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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:16 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 05:13:16 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 05:13:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:16 INFO - 'HOME': '/Users/cltbld', 05:13:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:16 INFO - 'LOGNAME': 'cltbld', 05:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:16 INFO - 'MOZ_NO_REMOTE': '1', 05:13:16 INFO - 'NO_EM_RESTART': '1', 05:13:16 INFO - 'PAGER': '/bin/cat', 05:13:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:16 INFO - 'PWD': '/builds/slave/test', 05:13:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:16 INFO - 'SHELL': '/bin/bash', 05:13:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:16 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:16 INFO - 'USER': 'cltbld', 05:13:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:16 INFO - 'XPC_FLAGS': '0x0', 05:13:16 INFO - 'XPC_SERVICE_NAME': '0', 05:13:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-XtRTmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-y5l3qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-QglW5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-RcVn01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-90SehA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-fWWeWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Q6AWM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-ysFWgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-SuV0Q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-0_jlUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-wGWy8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-u258EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-AYzMn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-qyxZte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-sRB1nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-8xjdqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:18 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)) 05:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-jcNovE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:18 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)) 05:13:18 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)) 05:13:18 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)) 05:13:18 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)) 05:13:18 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)) 05:13:18 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)) 05:13:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13:18 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 05:13:18 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 05:13:18 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 05:13:18 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 05:13:18 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 05:13:18 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 05:13:21 INFO - Downloading blessings-1.6.tar.gz 05:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:13:22 INFO - Installing collected packages: blessings 05:13:22 INFO - Running setup.py install for blessings 05:13:22 INFO - Successfully installed blessings 05:13:22 INFO - Cleaning up... 05:13:22 INFO - Return code: 0 05:13:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:22 INFO - Reading from file tmpfile_stdout 05:13:22 INFO - Current package versions: 05:13:22 INFO - blessings == 1.6 05:13:22 INFO - blobuploader == 1.2.4 05:13:22 INFO - docopt == 0.6.1 05:13:22 INFO - functools32 == 3.2.3-2 05:13:22 INFO - jsonschema == 2.5.1 05:13:22 INFO - manifestparser == 1.1 05:13:22 INFO - mozInstall == 1.12 05:13:22 INFO - mozcrash == 1.0 05:13:22 INFO - mozdebug == 0.1 05:13:22 INFO - mozdevice == 0.49 05:13:22 INFO - mozfile == 1.2 05:13:22 INFO - mozhttpd == 0.7 05:13:22 INFO - mozinfo == 0.9 05:13:22 INFO - mozleak == 0.1 05:13:22 INFO - mozlog == 3.4 05:13:22 INFO - moznetwork == 0.27 05:13:22 INFO - mozprocess == 0.24 05:13:22 INFO - mozprofile == 0.28 05:13:22 INFO - mozrunner == 6.13 05:13:22 INFO - mozscreenshot == 0.1 05:13:22 INFO - mozsystemmonitor == 0.3 05:13:22 INFO - moztest == 0.8 05:13:22 INFO - mozversion == 1.4 05:13:22 INFO - psutil == 3.1.1 05:13:22 INFO - requests == 1.2.3 05:13:22 INFO - wsgiref == 0.1.2 05:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:22 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')]} 05:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:23 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')]} 05:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:23 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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:23 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 05:13:23 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 05:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:23 INFO - 'HOME': '/Users/cltbld', 05:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:23 INFO - 'LOGNAME': 'cltbld', 05:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:23 INFO - 'MOZ_NO_REMOTE': '1', 05:13:23 INFO - 'NO_EM_RESTART': '1', 05:13:23 INFO - 'PAGER': '/bin/cat', 05:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:23 INFO - 'PWD': '/builds/slave/test', 05:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:23 INFO - 'SHELL': '/bin/bash', 05:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:23 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:23 INFO - 'USER': 'cltbld', 05:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:23 INFO - 'XPC_FLAGS': '0x0', 05:13:23 INFO - 'XPC_SERVICE_NAME': '0', 05:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 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)) 05:13:23 INFO - Cleaning up... 05:13:23 INFO - Return code: 0 05:13:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13:23 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')]} 05:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:23 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')]} 05:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:23 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 0x104a54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa229c5d490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, '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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 05:13:23 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 05:13:23 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 05:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:23 INFO - 'HOME': '/Users/cltbld', 05:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:23 INFO - 'LOGNAME': 'cltbld', 05:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:23 INFO - 'MOZ_NO_REMOTE': '1', 05:13:23 INFO - 'NO_EM_RESTART': '1', 05:13:23 INFO - 'PAGER': '/bin/cat', 05:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:23 INFO - 'PWD': '/builds/slave/test', 05:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:23 INFO - 'SHELL': '/bin/bash', 05:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:23 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:23 INFO - 'USER': 'cltbld', 05:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:23 INFO - 'XPC_FLAGS': '0x0', 05:13:23 INFO - 'XPC_SERVICE_NAME': '0', 05:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:13:23 INFO - Cleaning up... 05:13:23 INFO - Return code: 0 05:13:23 INFO - Running post-action listener: _resource_record_post_action 05:13:23 INFO - Running post-action listener: _start_resource_monitoring 05:13:23 INFO - Starting resource monitoring. 05:13:23 INFO - [mozharness: 2017-03-27 12:13:23.593297Z] Finished create-virtualenv step (success) 05:13:23 INFO - [mozharness: 2017-03-27 12:13:23.594228Z] Running install step. 05:13:23 INFO - Running pre-action listener: _resource_record_pre_action 05:13:23 INFO - Running main action method: install 05:13:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:23 INFO - Reading from file tmpfile_stdout 05:13:23 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:23 INFO - Reading from file tmpfile_stdout 05:13:23 INFO - Output received: 05:13:23 INFO - Usage: mozinstall [options] installer 05:13:23 INFO - Options: 05:13:23 INFO - -h, --help show this help message and exit 05:13:23 INFO - -d DEST, --destination=DEST 05:13:23 INFO - Directory to install application into. [default: 05:13:23 INFO - "/builds/slave/test"] 05:13:23 INFO - --app=APP Application being installed. [default: firefox] 05:13:23 INFO - mkdir: /builds/slave/test/build/application 05:13:23 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'] 05:13:23 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 05:13:38 INFO - Reading from file tmpfile_stdout 05:13:38 INFO - Output received: 05:13:38 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:13:38 INFO - Running post-action listener: _resource_record_post_action 05:13:38 INFO - [mozharness: 2017-03-27 12:13:38.450477Z] Finished install step (success) 05:13:38 INFO - [mozharness: 2017-03-27 12:13:38.450602Z] Running run-tests step. 05:13:38 INFO - Running pre-action listener: _resource_record_pre_action 05:13:38 INFO - Running main action method: run_tests 05:13:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:38 INFO - 2017-03-27 05:13:38.464 screenresolution[1621:7264] starting screenresolution argv=screenresolution get 05:13:38 INFO - 2017-03-27 05:13:38.467 screenresolution[1621:7264] Display 0: 1600x1200x32@60 05:13:38 INFO - 2017-03-27 05:13:38.473 screenresolution[1622:7266] starting screenresolution argv=screenresolution list 05:13:38 INFO - Available Modes on Display 0 05:13:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:38 INFO - Intel Iris: 05:13:38 INFO - Chipset Model: Intel Iris 05:13:38 INFO - Type: GPU 05:13:38 INFO - Bus: Built-In 05:13:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:38 INFO - Vendor: Intel (0x8086) 05:13:38 INFO - Device ID: 0x0a2e 05:13:38 INFO - Revision ID: 0x0009 05:13:38 INFO - Displays: 05:13:38 INFO - Display: 05:13:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:38 INFO - Display Serial Number: 1600x1200 60 05:13:38 INFO - Main Display: Yes 05:13:38 INFO - Mirror: Off 05:13:38 INFO - Online: Yes 05:13:38 INFO - Rotation: Supported 05:13:38 INFO - Return code: 0 05:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:13:38 INFO - Python 2.7.3 05:13:38 INFO - Return code: 0 05:13:38 INFO - grabbing minidump binary from tooltool 05:13:38 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')]} 05:13:38 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 0x104832d30>, '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 0x104759db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104827be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:13:38 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 05:13:38 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 05:13:38 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 05:13:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:13:38 INFO - Return code: 0 05:13:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:13:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:13:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:13:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:13:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:13:38 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/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 05:13:38 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/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0328 --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 05:13:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bxZlVnMzRb/Render', 05:13:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:38 INFO - 'HOME': '/Users/cltbld', 05:13:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:38 INFO - 'LOGNAME': 'cltbld', 05:13:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:13:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:38 INFO - 'MOZ_NO_REMOTE': '1', 05:13:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:13:38 INFO - 'NO_EM_RESTART': '1', 05:13:38 INFO - 'PAGER': '/bin/cat', 05:13:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:38 INFO - 'PWD': '/builds/slave/test', 05:13:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:13:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:38 INFO - 'SHELL': '/bin/bash', 05:13:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pn0OIwQBCu/Listeners', 05:13:38 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 05:13:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:38 INFO - 'USER': 'cltbld', 05:13:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:38 INFO - 'XPC_FLAGS': '0x0', 05:13:38 INFO - 'XPC_SERVICE_NAME': '0', 05:13:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:38 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/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 05:13:39 INFO - mozversion application_buildid: 20170327034016 05:13:39 INFO - mozversion application_changeset: 6188b6cab49e2bfa036b24d8112cda5f5f9416f0 05:13:39 INFO - mozversion application_display_name: Firefox 05:13:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:13:39 INFO - mozversion application_name: Firefox 05:13:39 INFO - mozversion application_remotingname: firefox 05:13:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:13:39 INFO - mozversion application_vendor: Mozilla 05:13:39 INFO - mozversion application_version: 53.0 05:13:39 INFO - mozversion platform_buildid: 20170327034016 05:13:39 INFO - mozversion platform_changeset: 6188b6cab49e2bfa036b24d8112cda5f5f9416f0 05:13:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:13:39 INFO - mozversion platform_version: 53.0 05:13:39 INFO - using testdate: 1490616819 05:13:39 INFO - actual date: 1490616819 05:13:39 INFO - starting webserver on 'localhost:49237' 05:13:39 INFO - SUITE-START | Running 2 tests 05:13:39 INFO - TEST-START | dromaeo_css 05:13:39 INFO - operating with platform_type : mac_ 05:13:39 INFO - Initialising browser for dromaeo_css test... 05:13:39 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpWijgNJ/profile http://localhost:49237/getInfo.html) 05:13:42 INFO - PROCESS | 1632 | 2017-03-27 05:13:42.123 plugin-container[1636:7434] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 05:13:42 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:42 INFO - PROCESS | 1632 | 2017-03-27 05:13:42.123 plugin-container[1636:7434] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 05:13:42 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:42 INFO - PROCESS | 1632 | 2017-03-27 05:13:42.123 plugin-container[1636:7434] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:13:42 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 05:13:42 INFO - PROCESS | 1632 | colorDepth:24 05:13:42 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 05:13:42 INFO - PROCESS | 1632 | __metrics 05:13:42 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:13:42 INFO - TEST-INFO | 1632: exit 0 05:13:42 INFO - Browser initialized. 05:13:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:42 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpWijgNJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:45 INFO - PROCESS | 1637 | 2017-03-27 05:13:45.253 plugin-container[1638:7558] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 05:13:45 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:45 INFO - PROCESS | 1637 | 2017-03-27 05:13:45.253 plugin-container[1638:7558] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 05:13:45 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:13:45 INFO - PROCESS | 1637 | 2017-03-27 05:13:45.253 plugin-container[1638:7558] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:13:54 INFO - PROCESS | 1637 | RSS: Main: 172998656 05:13:54 INFO - PROCESS | 1637 | 05:16:56 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 05:16:58 INFO - PROCESS | 1637 | RSS: Main: 188776448 05:16:58 INFO - PROCESS | 1637 | 05:20:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 05:20:01 INFO - PROCESS | 1637 | RSS: Main: 199729152 05:20:01 INFO - PROCESS | 1637 | 05:23:03 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 05:23:03 INFO - PROCESS | 1637 | RSS: Main: 202612736 05:23:03 INFO - PROCESS | 1637 | 05:26:05 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 05:26:06 INFO - PROCESS | 1637 | RSS: Main: 205697024 05:26:06 INFO - PROCESS | 1637 | 05:29:09 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 05:29:09 INFO - PROCESS | 1637 | RSS: Main: 208302080 05:29:09 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 05:32:12 INFO - PROCESS | 1637 | RSS: Main: 208699392 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | __start_tp_report 05:32:12 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 05:32:12 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 05:32:12 INFO - PROCESS | 1637 | |i|pagename|runs| 05:32:12 INFO - PROCESS | 1637 | |0;dojo.html;3539.964600353996;3561.4479755637885;3565.393883039883;3576.356255873945;3579.892603221904;4933.358663373757;5928.407159284069;5983.790567330145;6023.789167379143;6040.395960403957;4421.602055814976;6049.667268300242;6050.697465126748;6077.908831367533;6095.146679464868;5341.332333458327;5412.4587541245955;5448.482394172547;5452.754626041838;5474.671519708811;4038.676905847525;4930.901381972376;5093.2105523643895;5510.559155267589;5528.198411230369;5353.277307563468;5379.085555455582;5475.178723191513;5479.671219726829;5518.558515318765;5344.171653393711;5418.160185171305;5463.180522921554;5519.751611177507;5569.916451253234;5347.705876176848;5457.5633949283965;5473.17902314652;5479.068558345091;5507.752151153168;4881.941604237528;5751.942480575182;5970.462658360769;6098.54260930432;6137.60105593135;6260.966940454819;6301.180846489927;6303.905441418382;6338.809835704937;6353.904691429632;5423.620346575742;6381.004639098082;6419.3259707730485;6424.293327733945;6440.581362211488;6151.015837465984;6158.969205153946;6187.5668703190795;6208.81373558794;6213.5339849511465;6255.124282600348;6260.780872669526;6399.9360006400275;6433.260175079832;6478.255000674889;6243.843903902348;6288.654124023223;6290.339514351019;6307.653079080695;6307.905381419282;6397.584157029778;6405.711742971577;6407.231132264158;6417.807465776034;6419.358064193637;6227.564070515021;6250.312465628777;6263.686815659199;6271.749130034838;6293.30773614902;6279.874402511924;6320.778772742932;6327.557071004985;6335.018072198816;6351.888419526565;5158.74206289684;5160.509751573594;5162.5095615916425;5172.353455818023;5183.81856635016;4505.5719706627815;4535.138323718483;4553.931691024637;4624.745638989821;4681.204195286809;5978.1929439525975;6027.336992930774;6068.271807383142;6098.878022439526;6105.633661980295;6393.296737358887;6439.162908821823;6458.773942911976;6467.805965821033;6479.514036447285;5809.186713860063;5826.737796799154;5830.183774271605;5900.4099590041515;5935.910961335583;5524.8342549722765;5531.778728850801;5532.336119665585;5545.1682247663175;5558.749856256478;6370.267419246846;6396.584222025554;6396.712147953353;6404.48764098871;6434.485241180788;6173.41352571505;6255.687215639109;6277.121203031488;6278.340774218641;6353.237611486651;6620.238672552526;6641.468682505291;6650.800475985729;6657.900131498128;6660.6336651484635;5575.4424557543925;6359.300476947439;6366.808995730135;6372.107904893226;6385.87228255423;5191.90969896326;5911.290645122441;6194.628322300677;6392.680365981775;6454.096426500199;5023.547880690755;6255.0617407387435;6292.119103325446;6298.433141017331;6307.7476900925285;5211.60912931524;6250.1874756281395;6271.4669253114;6289.7169627365965;6297.370262973666;6007.249093863267;6090.23872015998;6111.113888486234;6225.688715564113;6253.593516421418;6203.658798766111;6215.689215539295;6259.280182779039;6274.59215151032;6303.936960630518;6250.343713909974;6259.467945224706;6270.686465676789;6270.717817698306;6283.8114856554375 05:32:12 INFO - PROCESS | 1637 | |1;ext.html;494.87909884112514;499.6933020152303;503.7355388421081;514.2363590068749;537.1942086869694;35200;41810.95472613186;41822.95442613936;41897.79051104744;41934.58065419352;43306.91732706676;43429.565704342946;43870.34194487085;43926.7803660982;44036;9481.47851868147;9734.123928846402;9756.560954757053;11309.943450282737;11747.647570572897;11492.827607585892;11548.249363791369;11612.477438515245;11652.650420487356;11696.7660646787;11480.425978701032;11601.361925094116;11639.301641901513;11673.299602023862;11678.357690327028;11557.76884462306;11562.306261624246;11587.478563464621;11639.941800290944;11668.766624667544;11500.137489688306;11577.363245021521;11591.884081159165;11612.593559225386;11653.883461165364;38405;38733.8379848605;38750.41874371887;38782.8365149046;38867;3274.3123943971555;3805.8858234253016;3866.8066596670055;3867.207222519377;3907.2966865964113;28520.0017999369;29012;29815.85092074526;30520;30641.69358306433;3310.2717402171484;3390.355832391838;3502.4221003534385;3539.9557130646263;3542.0613537412605;506951;507706;513143;513668;516700.41649791884;59170.40829591649;59566.404335956955;59585.106223406685;59647.10529342063;59682.70158649179;60150.699246504366;61738.0739288911;62075;62096;62450.68774656098;16233.59416014609;16485.423010194587;16997.660046798996;17036.31854725822;17086.743698844526;12041.217320874115;12322.383880805924;12964;13060.281684507443;13245.73508529824;18269.54326141857;18308.450746477625;18311.54221144482;18332.08339583042;18353.63292734138;6410.775622853178;8047.356211503065;8169.877451838227;8182.386321025947;8233.83532329362;797.6649807081039;895.1183084661603;902.5171533229691;915.3638221436171;925.4632313258429;30424.239394014883;30646.846765766473;30878.228044298623;30910.0726978191;31071.378572428875;1090.8745494268103;1100.4607742206422;1118.3960661243;1151.614209239914;1256.8805963433458;15099.924500377647;15605.921970390076;15854.365825367087;16205.594860128354;16299.266533006043;516192;683107;683514;684272;687603;55199;55498.44501555013;55786.88426231534;55885.88228235413;56224.71887640536;1449.8332691740375;1469.1919444305588;1494.9327280272196;1499.1005396762073;1503.9774403383958;24230.151944682555;27888.58167127535;33516.162095947795;34191;37754.43368349532;53475;67375.65248694901;67381;67414.6517069646;67628.6618566904;598651;604720;606052;606489;607508;41594.58405415907;42410.57589424066;47618.285725714835;48859;49046.509534904195;42411.57588424076;51322.23016654828;51529.96940061253;53048.73475632597;53596.46403536071;32265;34861.30277394387;35231.82384088063;36313;36329;34682.65317346895;34748.30503389968;35161.64838351686;36172.27655446929;36251.81874090613;34655;41414.58585414107;41950.79024604979;42461;42769.572304277805;33860.49209261814;34452.31095378028;35099;35757.28485430329;36067.639323606425;26028.479430410905;27409.862950685118;27422;27469.901203952417;27624 05:32:12 INFO - PROCESS | 1637 | |2;jquery.html;361.21796310986707;401.5161730115211;402.68389314388213;404.97570145791263;428.91850548395803;13865.792013119812;20630.6905396419;21402.46493837655;21989.560208795836;22038.559228815415;21762.99999999996;21879.562408751815;22080.006399712012;22325.330240092822;22428.551428971412;5928.0811474221455;6213.161223234851;6227.470664993481;6234.625922444668;6248.843778905518;5926.555508336871;6102.511799056065;6106.633601983873;6175.598586091912;6177.289611694645;6088.66512341821;6122.112293717409;6125.356837532062;6134.049222370518;6140.53945954053;5858.736356863951;6113.480354169878;6119.326874043852;6181.134641150243;6223;5231.581473482112;5968.462838344526;5988.940110598882;6104.145419641254;6120.724567394478;13533.458661653522;13842.653933651638;13849.653758656013;13851.891848652083;18594.256229750794;2613.333599932005;2668.7731542818883;2683.2755156107937;2688.1263589333544;2729.3040274730015;9136.908630913673;10674.573017079307;10813.62152324665;12825.67935801603;15209.391624335014;1589.7138515067284;1879.5676994291252;1883.7551118354663;1892.1485331600697;1894.4316704988587;113429;136110.6388936108;136945;137053;142759.57240427568;10075.848862267072;10087.29388942767;12001.499812523434;12168.939155304166;12215.75568488643;14432.061915975428;15011.324490397958;16011.599710007344;16015.878888477928;16043.839561604467;7597.924020759721;8734.563271836383;8989;9083.229614967366;9107.499087550115;8872.911270887209;8981.236594889508;9001.18989290967;9128.45229286245;9199.126083022102;3935;4890.975545122252;4927;4943.8516844494725;4962.776675049559;2076.273304343498;2105.5683584865064;2107.683847422868;2111.1133324003954;2116.693079503494;1043.9217058720626;1051.3113910388709;1053.3311347294523;1065.211743309945;1072.3726619927274;21563.89218053931;21862;21876;21931.723313953946;21949.451263718536;1083.1172594335592;1088.8584484017026;1093.6336327330434;1117.7038084907506;1131.547381047571;10526.684199474028;11216.943915280372;11345.602903898323;11497;11541.76916461666;781720;790997;794305;807103;825734;161793;162160.99999999764;165258.3474165267;168673.3132668682;171001;1020.5968642386216;1032.4063663393579;1058.2539309786582;1062.208654552348;1063.335415365381;18682.906585466986;19085.04574771228;19319.874813770755;19392.806071939664;19399.806001940364;49994.500054998985;62758;62797.05804413029;64451.67774161099;65145;90438.09561904297;91720.54139729525;91888.54055729946;95273;97472.5126374392;6793.72825086991;8501.447405918596;8656.956715216636;8706.521141337287;8745.431546949429;11214.83177752318;12117.636470905887;12711.919486843723;12900.93549532284;13072.411741471848;10659.25385223027;11477.82783258235;11535.01952334061;11560.826587601025;11662.300261984308;9445.433274003582;10462;10613.681589552325;10706.464676765974;10977.17671174681;9826.9017309826;10075.546600402853;11706.765864682488;11948.820767688318;11979.460924258256;7432.584410066642;8407.789805254917;8555.144485551395;8612.397132200937;8727.170918762708;10867.293625914606;11612.954834064636;11846.466908988943;11906.107041971714;11908.166428349932 05:32:12 INFO - PROCESS | 1637 | |3;mootools.html;1413.342795600047;1485.7102864941335;1495.0730547060816;1498.9475368362107;1501.632100135467;7686;7919.366450683946;8148.877766833502;8583.098774628668;8639.43843650163;7164.319389657987;8127.146649601796;8418.494890306585;8420.157984201576;8512.914870851306;2374.8693821839793;2401.6997875265592;2404.5311164322966;2409.156795121702;2412.589859723851;2374.169040835702;2383.392234980078;2389.0921449849034;2423.8909249083745;2428.2836563213896;2367.597508423572;2377.072941552796;2382.03527571333;2387.415083304584;2395.964060539093;2335.1126571902737;2340.918067867625;2342.648602709607;2362.9527409451803;2363.5745565798234;2393.186316652329;2394.736578976311;2406.5307265083316;2413.4690368118986;2413.54142712884;8459.53472559009;8519.318454523624;8541.188587084218;8551.444156129894;8604.48373097616;5212.843614691566;5346.385165705955;5354.544863686591;5363.678179309254;5378.247045413606;3599.8560057597665;3614.9277014459826;3642.8907132786057;3652.1782598915297;3668.853245870162;1126.9887301126967;1262.6085913366794;1272.274803362084;1292.877176668215;1317;8928.553572321358;9657.661981830603;10885.782284354347;10958.506867190996;10970.451477426177;4471.7987690554;4730.929036064462;4740.597049250783;4773.331733557305;4778.522147785194;8699.913000870038;8940.642374305085;9525.857112143323;9634.470104144199;9674.806503870024;5313.30926979498;5341.626086174008;5372.489613486712;5381.031414345455;5396;4448.977755111268;4487.88780280489;4888.120138375127;4923.852284431473;4945.703257804544;3963.4253033310583;4041.2725709372594;4076.7553946763287;4105.753654780723;4108.4864391951005;1321.7554752370843;1327.0909427042507;1328.249539010456;1337.77257866163;1341.3092257487401;860.5138096975189;925.0379605210641;936.6253498600478;941.6939494664125;977.2768151567789;5006.249062640561;5603.411641777555;5625.465580769902;6001.279846418458;6028.758849646053;968.6754937096151;1034.7568321444455;1040.7710303733168;1049.5172220778527;1107.5735841700841;3431.9828400858337;3940.408938659224;4051.2100140472326;4070.0231944333145;4145.191687620945;9992.65025724096;10142.746431339128;10190.184785217165;10297.845532317022;10323.380597164194;9965;9990.100890919852;10169;10278.22913281507;10279.229057820694;8043.839123217503;8071.878921816177;8108.189181081844;8133.877991830127;8205.58972051395;5755.683437410982;5782.797602083907;5785.681787501728;5854.443827836349;5933.525317974465;9242.768930776785;9278.860817087883;9300.674476393293;9329.72010839676;9403.106704863027;9418.246540276625;9482.336236463381;9523.28575356837;9546.761330966781;9596;6360.745570177326;6554.31179726141;6740.797776066725;6807.9319206807295;6948.6178260196175;6890.20762612306;7995.680172793023;8228.382871284797;8232.17678232172;8261.339092872677;7010.719571217094;7111.964440177766;7128;7141.500094993508;7235.63821808918;7012.368886800213;7023.262557431396;7050.506464547638;7053.470989675692;7134.32223938725;6531;6533.901991470227;6592.967035164794;6595.439387651912;6607.372299631527;5157.974210128926;5161.690298582097;5201.869953251199;5222.8955420892125;5233.525335837804;5181.740912954413;5221.05810113362;5265.631405801557;5277.947220527899;5340.946590534045 05:32:12 INFO - PROCESS | 1637 | |4;prototype.html;361.761237583195;367.62502247707334;376.7231085152412;380.92191100824334;382.09824813440275;4336.86989390318;4928.260760885869;5082.466341034194;5166.4316925138255;5451.412302390042;4149.087200815823;5172.689638621685;5272.446393128724;5374.811881584145;5394.163904594784;4196.97901510492;4302.494127055536;4336.696431249814;4343.501491985276;4366.759828209447;3903.63829882921;3955.3473676843287;4101.882569247378;4233.216854881842;4427.358033085216;3950.6246906543975;4281.229378711831;4286.892827679318;4294.312909934411;4337.240982827992;3591.455674060166;4257.701960862741;4286.271333873234;4319.82720691172;4343.370211319341;3834.2523207974455;3838.9634798604498;3844.019774957396;3864.845406183749;3888.008557817767;5118.948810511884;5275.314209152824;5305.522502974751;5373.3014708088085;5376.973115134439;4698.6241100712205;4966.925496117561;4985.975070124662;4994.525520075587;5006.974965125188;4592.609628181609;5122.9262442534555;5749.8275051748515;5835.891180675658;5877.38287479817;4179.101493178981;4196.412502249687;4205.285101532747;4208.368744688321;4276.807543660543;5262.394824595182;5289.471052894718;5941.336425800782;6121.3572574879445;6137.907931380988;3422.8117453540294;3619.2761447710564;3739.8878033659034;4111.671066314688;4354.934675979863;3565.8751943681846;4279.679024073144;4333.22673488961;4368.825246990148;4374.496932852762;4966.230234313687;4983.651144419856;4989.25161225819;5008.524190201925;5010.949890501049;5076.619253555924;5454.400015998237;5912.3496415394275;5912.881742365129;5927.762889484372;1339.8745054154556;1409.2530958591963;1427.8143841300564;1429.434768928022;1431.6921861799763;917.655879045358;958.9616415343309;965.1024547171058;981.293468702533;1002.5338217728792;4776.880577985578;4954.281629163823;5091.719955402415;5111.747621832675;5170.93162734909;4967.081089998362;5645.943540564624;5695.316562012502;5782.913256301159;5800.709964501758;4209.5579964103945;4720.811167553259;4805.8946442317765;4988.152014157602;5167.405748338989;2513.416547575016;2649.523085844528;3005.2637103909683;3013.9903132450872;3016.5173572228337;4912.59990902591;5169.353830771153;5461.8152738904755;5769.788344447629;5955.642661440327;5032.6980381177245;5196.948030519722;5901.704914754245;5974.2233509643465;5986.042233242747;4879.731614761222;5904.08486684556;5932.775209296514;5947.888499582672;5976.342602313829;5064.974675126601;5401.405845357086;5402.6758394496455;5461.508464238238;5702.486776190163;5315.468453154808;5638.718064096861;5694.17434471991;5709.714514274353;5723.883842650643;5361.758720857649;5377.489138531833;5717.971410142923;5887.41125887425;5958.59972906358;3756.6431189037;4119.134981653886;4145;4156.605122513356;4256.616904478612;5024.71375524143;5481.808136715196;5961.970190149022;5976.579485308457;6022.1870047543025;5821.952048631118;5834.562407819346;5842.269716285465;5851.590388672922;5884.616730224266;4502.887427814331;5490.313710786152;5738.614217611629;5783.298688839062;5792.797252096157;5181.3005244291535;5310.751973286197;5641.830745077655;5676.148577713376;5691.459311365414;5265.815696450606;5316;5578.940001399721;5793.246877905845;5818.883622327445;5268.736563171749;5417.891642167214;5612.747426365741;5649.887002260014;5698.8290351289525 05:32:12 INFO - PROCESS | 1637 | |5;yui.html;661.7749965011898;733.6295170938675;735.6211551051206;854.8204876975835;861.8491763941313;15199.240037998097;15793.60515987101;15924.840751592481;16075.758863617055;16231.188440577967;14787.778183327258;14796.334164962576;14821.412577251875;15219.162946037963;15301.311440985155;886.9911300887005;889.995550022249;904.4754042655244;905.5562774240641;921.8294615496121;739.8779201431757;905.4386280506062;905.7373361725092;907.9591418386156;908.6229214875851;929.330881765128;942.2744486745237;959.395580784105;959.4531117263163;960.279790157382;929.4330458420358;947.9052094790535;951.5860600638745;952.6808519146094;953.3374304858127;708.88303429934;710.4103594017005;715.9427245820322;717.8133685241822;718.6550455781254;15680.294386752623;15728.842711572966;15815.446459373867;16449;16768.413105541247;14588.27058647074;14603.342849571794;14805.520702634863;14834;14941.103533788008;11916.761664766656;12049;12101.939490302491;12116.212446191057;12140.028797696163;3716.981415092934;3834.884953451401;3845.8846234613006;3877.2051729395407;4003.5796241394523;87487;109098;109326;111751.44124279408;113641;10899.945500272606;10978.615748448925;11309;11336;11590.188686791844;22703.772962270497;23097.88451057734;23570.528589428115;23574.29277121689;23620.881895590413;7985.241402066795;8023.518588884685;8028.718994835152;8163.38774591908;8234.835303293901;4387.780610969438;4405.757683327383;4471.19518486669;4472.2620767573635;4478.081993191388;3739.364308067635;3798.0504873781556;3815.980920095382;3818.3317919364;3849.9230015399535;684.7295318349225;690.751603530515;702.4871843554132;704.1163340008359;711.9394851437582;676.4791110844623;687.7180356053993;713.9535930164523;734.9412047036225;765.4259305520859;9623.278254130968;9911.65309214174;10340.224483163793;10887.510062047228;11032.724181895517;596.880623875218;680.8502129531495;690.775497963154;710.8649356622225;727.1310783613636;5055.317532133115;5166.302549155891;5195.506426889515;5212.244224587414;5222;105418;105631.4718426403;109090;110132.4493377544;112590;94795;96237.0376296242;97496;98658.52012219823;99575;85430;85870.57064714636;87086.56456717802;87628;88811.66782498138;8124.918750812652;8422.663093476192;9908.306418550921;9920.107190352903;9992.950035249778;44558.77720611376;50557.49442505528;55479.445205547425;56473.71763141158;57173.42826571681;54859.72570137124;73044.26955730375;74810;75382.62308688421;76120.23879761131;10528.94735526348;11858.466369013588;11974.760504790029;12012.159148859497;12147.757044858876;12947.805782913452;13175.868241317465;13291.601251962456;13316.134451260637;13458.394372253468;12057.81913271284;12404.441800119197;12566.937165314115;12614.936925315315;12791;12327.93836030814;12377.133600647869;12386.876131238572;12677.239365637723;12854.228746274879;11122;11338.716532086764;11930.582429614922;12030.939845300718;12084.818727718915;8882.600282987152;8940.776480587778;9295.581698823704;9429.764255893657;9501.904980950101;8358.749237522885;8541.786455338666;9494.76872934846;10274.537645805807;10277.74305642365 05:32:12 INFO - PROCESS | 1637 | __end_tp_report 05:32:12 INFO - PROCESS | 1637 | __start_cc_report 05:32:12 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,380 05:32:12 INFO - PROCESS | 1637 | __end_cc_report 05:32:12 INFO - PROCESS | 1637 | __startTimestamp1490617932706__endTimestamp 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | ------- Summary: start ------- 05:32:12 INFO - PROCESS | 1637 | Number of tests: 6 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5878.13 Median:6187.57 stddev:667.60 (10.8%) stddev-sans-first:643.95 05:32:12 INFO - PROCESS | 1637 | Values: 3540.0 3561.4 3565.4 3576.4 3579.9 4933.4 5928.4 5983.8 6023.8 6040.4 4421.6 6049.7 6050.7 6077.9 6095.1 5341.3 5412.5 5448.5 5452.8 5474.7 4038.7 4930.9 5093.2 5510.6 5528.2 5353.3 5379.1 5475.2 5479.7 5518.6 5344.2 5418.2 5463.2 5519.8 5569.9 5347.7 5457.6 5473.2 5479.1 5507.8 4881.9 5751.9 5970.5 6098.5 6137.6 6261.0 6301.2 6303.9 6338.8 6353.9 5423.6 6381.0 6419.3 6424.3 6440.6 6151.0 6159.0 6187.6 6208.8 6213.5 6255.1 6260.8 6399.9 6433.3 6478.3 6243.8 6288.7 6290.3 6307.7 6307.9 6397.6 6405.7 6407.2 6417.8 6419.4 6227.6 6250.3 6263.7 6271.7 6293.3 6279.9 6320.8 6327.6 6335.0 6351.9 5158.7 5160.5 5162.5 5172.4 5183.8 4505.6 4535.1 4553.9 4624.7 4681.2 5978.2 6027.3 6068.3 6098.9 6105.6 6393.3 6439.2 6458.8 6467.8 6479.5 5809.2 5826.7 5830.2 5900.4 5935.9 5524.8 5531.8 5532.3 5545.2 5558.7 6370.3 6396.6 6396.7 6404.5 6434.5 6173.4 6255.7 6277.1 6278.3 6353.2 6620.2 6641.5 6650.8 6657.9 6660.6 5575.4 6359.3 6366.8 6372.1 6385.9 5191.9 5911.3 6194.6 6392.7 6454.1 5023.5 6255.1 6292.1 6298.4 6307.7 5211.6 6250.2 6271.5 6289.7 6297.4 6007.2 6090.2 6111.1 6225.7 6253.6 6203.7 6215.7 6259.3 6274.6 6303.9 6250.3 6259.5 6270.7 6270.7 6283.8 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:73135.24 Median:36212.05 stddev:158402.37 (437.4%) stddev-sans-first:158752.83 05:32:12 INFO - PROCESS | 1637 | Values: 494.9 499.7 503.7 514.2 537.2 35200.0 41811.0 41823.0 41897.8 41934.6 43306.9 43429.6 43870.3 43926.8 44036.0 9481.5 9734.1 9756.6 11309.9 11747.6 11492.8 11548.2 11612.5 11652.7 11696.8 11480.4 11601.4 11639.3 11673.3 11678.4 11557.8 11562.3 11587.5 11639.9 11668.8 11500.1 11577.4 11591.9 11612.6 11653.9 38405.0 38733.8 38750.4 38782.8 38867.0 3274.3 3805.9 3866.8 3867.2 3907.3 28520.0 29012.0 29815.9 30520.0 30641.7 3310.3 3390.4 3502.4 3540.0 3542.1 506951.0 507706.0 513143.0 513668.0 516700.4 59170.4 59566.4 59585.1 59647.1 59682.7 60150.7 61738.1 62075.0 62096.0 62450.7 16233.6 16485.4 16997.7 17036.3 17086.7 12041.2 12322.4 12964.0 13060.3 13245.7 18269.5 18308.5 18311.5 18332.1 18353.6 6410.8 8047.4 8169.9 8182.4 8233.8 797.7 895.1 902.5 915.4 925.5 30424.2 30646.8 30878.2 30910.1 31071.4 1090.9 1100.5 1118.4 1151.6 1256.9 15099.9 15605.9 15854.4 16205.6 16299.3 516192.0 683107.0 683514.0 684272.0 687603.0 55199.0 55498.4 55786.9 55885.9 56224.7 1449.8 1469.2 1494.9 1499.1 1504.0 24230.2 27888.6 33516.2 34191.0 37754.4 53475.0 67375.7 67381.0 67414.7 67628.7 598651.0 604720.0 606052.0 606489.0 607508.0 41594.6 42410.6 47618.3 48859.0 49046.5 42411.6 51322.2 51530.0 53048.7 53596.5 32265.0 34861.3 35231.8 36313.0 36329.0 34682.7 34748.3 35161.6 36172.3 36251.8 34655.0 41414.6 41950.8 42461.0 42769.6 33860.5 34452.3 35099.0 35757.3 36067.6 26028.5 27409.9 27422.0 27469.9 27624.0 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:42712.23 Median:21663.45 stddev:133299.61 (615.3%) stddev-sans-first:133635.62 05:32:12 INFO - PROCESS | 1637 | Values: 361.2 401.5 402.7 405.0 428.9 13865.8 20630.7 21402.5 21989.6 22038.6 21763.0 21879.6 22080.0 22325.3 22428.6 5928.1 6213.2 6227.5 6234.6 6248.8 5926.6 6102.5 6106.6 6175.6 6177.3 6088.7 6122.1 6125.4 6134.0 6140.5 5858.7 6113.5 6119.3 6181.1 6223.0 5231.6 5968.5 5988.9 6104.1 6120.7 13533.5 13842.7 13849.7 13851.9 18594.3 2613.3 2668.8 2683.3 2688.1 2729.3 9136.9 10674.6 10813.6 12825.7 15209.4 1589.7 1879.6 1883.8 1892.1 1894.4 113429.0 136110.6 136945.0 137053.0 142759.6 10075.8 10087.3 12001.5 12168.9 12215.8 14432.1 15011.3 16011.6 16015.9 16043.8 7597.9 8734.6 8989.0 9083.2 9107.5 8872.9 8981.2 9001.2 9128.5 9199.1 3935.0 4891.0 4927.0 4943.9 4962.8 2076.3 2105.6 2107.7 2111.1 2116.7 1043.9 1051.3 1053.3 1065.2 1072.4 21563.9 21862.0 21876.0 21931.7 21949.5 1083.1 1088.9 1093.6 1117.7 1131.5 10526.7 11216.9 11345.6 11497.0 11541.8 781720.0 790997.0 794305.0 807103.0 825734.0 161793.0 162161.0 165258.3 168673.3 171001.0 1020.6 1032.4 1058.3 1062.2 1063.3 18682.9 19085.0 19319.9 19392.8 19399.8 49994.5 62758.0 62797.1 64451.7 65145.0 90438.1 91720.5 91888.5 95273.0 97472.5 6793.7 8501.4 8657.0 8706.5 8745.4 11214.8 12117.6 12711.9 12900.9 13072.4 10659.3 11477.8 11535.0 11560.8 11662.3 9445.4 10462.0 10613.7 10706.5 10977.2 9826.9 10075.5 11706.8 11948.8 11979.5 7432.6 8407.8 8555.1 8612.4 8727.2 10867.3 11613.0 11846.5 11906.1 11908.2 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5519.54 Median:5327.13 stddev:2929.98 (55.0%) stddev-sans-first:2921.94 05:32:12 INFO - PROCESS | 1637 | Values: 1413.3 1485.7 1495.1 1498.9 1501.6 7686.0 7919.4 8148.9 8583.1 8639.4 7164.3 8127.1 8418.5 8420.2 8512.9 2374.9 2401.7 2404.5 2409.2 2412.6 2374.2 2383.4 2389.1 2423.9 2428.3 2367.6 2377.1 2382.0 2387.4 2396.0 2335.1 2340.9 2342.6 2363.0 2363.6 2393.2 2394.7 2406.5 2413.5 2413.5 8459.5 8519.3 8541.2 8551.4 8604.5 5212.8 5346.4 5354.5 5363.7 5378.2 3599.9 3614.9 3642.9 3652.2 3668.9 1127.0 1262.6 1272.3 1292.9 1317.0 8928.6 9657.7 10885.8 10958.5 10970.5 4471.8 4730.9 4740.6 4773.3 4778.5 8699.9 8940.6 9525.9 9634.5 9674.8 5313.3 5341.6 5372.5 5381.0 5396.0 4449.0 4487.9 4888.1 4923.9 4945.7 3963.4 4041.3 4076.8 4105.8 4108.5 1321.8 1327.1 1328.2 1337.8 1341.3 860.5 925.0 936.6 941.7 977.3 5006.2 5603.4 5625.5 6001.3 6028.8 968.7 1034.8 1040.8 1049.5 1107.6 3432.0 3940.4 4051.2 4070.0 4145.2 9992.7 10142.7 10190.2 10297.8 10323.4 9965.0 9990.1 10169.0 10278.2 10279.2 8043.8 8071.9 8108.2 8133.9 8205.6 5755.7 5782.8 5785.7 5854.4 5933.5 9242.8 9278.9 9300.7 9329.7 9403.1 9418.2 9482.3 9523.3 9546.8 9596.0 6360.7 6554.3 6740.8 6807.9 6948.6 6890.2 7995.7 8228.4 8232.2 8261.3 7010.7 7112.0 7128.0 7141.5 7235.6 7012.4 7023.3 7050.5 7053.5 7134.3 6531.0 6533.9 6593.0 6595.4 6607.4 5158.0 5161.7 5201.9 5222.9 5233.5 5181.7 5221.1 5265.6 5277.9 5340.9 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4643.54 Median:5101.73 stddev:1364.85 (26.8%) stddev-sans-first:1330.30 05:32:12 INFO - PROCESS | 1637 | Values: 361.8 367.6 376.7 380.9 382.1 4336.9 4928.3 5082.5 5166.4 5451.4 4149.1 5172.7 5272.4 5374.8 5394.2 4197.0 4302.5 4336.7 4343.5 4366.8 3903.6 3955.3 4101.9 4233.2 4427.4 3950.6 4281.2 4286.9 4294.3 4337.2 3591.5 4257.7 4286.3 4319.8 4343.4 3834.3 3839.0 3844.0 3864.8 3888.0 5118.9 5275.3 5305.5 5373.3 5377.0 4698.6 4966.9 4986.0 4994.5 5007.0 4592.6 5122.9 5749.8 5835.9 5877.4 4179.1 4196.4 4205.3 4208.4 4276.8 5262.4 5289.5 5941.3 6121.4 6137.9 3422.8 3619.3 3739.9 4111.7 4354.9 3565.9 4279.7 4333.2 4368.8 4374.5 4966.2 4983.7 4989.3 5008.5 5010.9 5076.6 5454.4 5912.3 5912.9 5927.8 1339.9 1409.3 1427.8 1429.4 1431.7 917.7 959.0 965.1 981.3 1002.5 4776.9 4954.3 5091.7 5111.7 5170.9 4967.1 5645.9 5695.3 5782.9 5800.7 4209.6 4720.8 4805.9 4988.2 5167.4 2513.4 2649.5 3005.3 3014.0 3016.5 4912.6 5169.4 5461.8 5769.8 5955.6 5032.7 5196.9 5901.7 5974.2 5986.0 4879.7 5904.1 5932.8 5947.9 5976.3 5065.0 5401.4 5402.7 5461.5 5702.5 5315.5 5638.7 5694.2 5709.7 5723.9 5361.8 5377.5 5718.0 5887.4 5958.6 3756.6 4119.1 4145.0 4156.6 4256.6 5024.7 5481.8 5962.0 5976.6 6022.2 5822.0 5834.6 5842.3 5851.6 5884.6 4502.9 5490.3 5738.6 5783.3 5792.8 5181.3 5310.8 5641.8 5676.1 5691.5 5265.8 5316.0 5578.9 5793.2 5818.9 5268.7 5417.9 5612.7 5649.9 5698.8 05:32:12 INFO - PROCESS | 1637 | 05:32:12 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:21211.59 Median:27806.41 stddev:31253.93 (112.4%) stddev-sans-first:31303.52 05:32:12 INFO - PROCESS | 1637 | Values: 661.8 733.6 735.6 854.8 861.8 15199.2 15793.6 15924.8 16075.8 16231.2 14787.8 14796.3 14821.4 15219.2 15301.3 887.0 890.0 904.5 905.6 921.8 739.9 905.4 905.7 908.0 908.6 929.3 942.3 959.4 959.5 960.3 929.4 947.9 951.6 952.7 953.3 708.9 710.4 715.9 717.8 718.7 15680.3 15728.8 15815.4 16449.0 16768.4 14588.3 14603.3 14805.5 14834.0 14941.1 11916.8 12049.0 12101.9 12116.2 12140.0 3717.0 3834.9 3845.9 3877.2 4003.6 87487.0 109098.0 109326.0 111751.4 113641.0 10899.9 10978.6 11309.0 11336.0 11590.2 22703.8 23097.9 23570.5 23574.3 23620.9 7985.2 8023.5 8028.7 8163.4 8234.8 4387.8 4405.8 4471.2 4472.3 4478.1 3739.4 3798.1 3816.0 3818.3 3849.9 684.7 690.8 702.5 704.1 711.9 676.5 687.7 714.0 734.9 765.4 9623.3 9911.7 10340.2 10887.5 11032.7 596.9 680.9 690.8 710.9 727.1 5055.3 5166.3 5195.5 5212.2 5222.0 105418.0 105631.5 109090.0 110132.4 112590.0 94795.0 96237.0 97496.0 98658.5 99575.0 85430.0 85870.6 87086.6 87628.0 88811.7 8124.9 8422.7 9908.3 9920.1 9993.0 44558.8 50557.5 55479.4 56473.7 57173.4 54859.7 73044.3 74810.0 75382.6 76120.2 10528.9 11858.5 11974.8 12012.2 12147.8 12947.8 13175.9 13291.6 13316.1 13458.4 12057.8 12404.4 12566.9 12614.9 12791.0 12327.9 12377.1 12386.9 12677.2 12854.2 11122.0 11338.7 11930.6 12030.9 12084.8 8882.6 8940.8 9295.6 9429.8 9501.9 8358.7 8541.8 9494.8 10274.5 10277.7 05:32:12 INFO - PROCESS | 1637 | -------- Summary: end -------- 05:32:12 INFO - PROCESS | 1637 | 05:32:13 INFO - TEST-INFO | 1637: exit 0 05:32:13 INFO - TEST-OK | dromaeo_css | took 1114228ms 05:32:13 INFO - TEST-START | kraken 05:32:13 INFO - operating with platform_type : mac_ 05:32:13 INFO - Initialising browser for kraken test... 05:32:13 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpCd1SWM/profile http://localhost:49237/getInfo.html) 05:32:15 INFO - PROCESS | 1650 | 2017-03-27 05:32:15.900 plugin-container[1652:9979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 05:32:15 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:15 INFO - PROCESS | 1650 | 2017-03-27 05:32:15.901 plugin-container[1652:9979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881b, name = 'com.apple.CFPasteboardClient' 05:32:15 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:15 INFO - PROCESS | 1650 | 2017-03-27 05:32:15.901 plugin-container[1652:9979] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:32:15 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:32:15 INFO - PROCESS | 1650 | colorDepth:24 05:32:15 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:32:15 INFO - PROCESS | 1650 | __metrics 05:32:16 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:32:16 INFO - TEST-INFO | 1650: exit 0 05:32:16 INFO - Browser initialized. 05:32:16 INFO - Running cycle 1/1 for kraken test... 05:32:16 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpCd1SWM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:19 INFO - PROCESS | 1653 | 2017-03-27 05:32:19.006 plugin-container[1654:10103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 05:32:19 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:19 INFO - PROCESS | 1653 | 2017-03-27 05:32:19.007 plugin-container[1654:10103] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 05:32:19 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:32:19 INFO - PROCESS | 1653 | 2017-03-27 05:32:19.007 plugin-container[1654:10103] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:32:28 INFO - PROCESS | 1653 | RSS: Main: 173088768 05:32:28 INFO - PROCESS | 1653 | 05:32:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 05:32:32 INFO - PROCESS | 1653 | RSS: Main: 186134528 05:32:32 INFO - PROCESS | 1653 | 05:32:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 05:32:35 INFO - PROCESS | 1653 | RSS: Main: 193896448 05:32:35 INFO - PROCESS | 1653 | 05:32:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 05:32:39 INFO - PROCESS | 1653 | RSS: Main: 197447680 05:32:39 INFO - PROCESS | 1653 | 05:32:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 05:32:41 INFO - PROCESS | 1653 | RSS: Main: 198762496 05:32:41 INFO - PROCESS | 1653 | 05:32:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:32:44 INFO - PROCESS | 1653 | RSS: Main: 198860800 05:32:44 INFO - PROCESS | 1653 | 05:32:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 05:32:47 INFO - PROCESS | 1653 | RSS: Main: 203186176 05:32:47 INFO - PROCESS | 1653 | 05:32:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 05:32:51 INFO - PROCESS | 1653 | RSS: Main: 203399168 05:32:51 INFO - PROCESS | 1653 | 05:32:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 05:32:55 INFO - PROCESS | 1653 | RSS: Main: 202768384 05:32:55 INFO - PROCESS | 1653 | 05:32:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:32:57 INFO - PROCESS | 1653 | RSS: Main: 202014720 05:32:57 INFO - PROCESS | 1653 | 05:32:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:32:59 INFO - PROCESS | 1653 | RSS: Main: 202199040 05:32:59 INFO - PROCESS | 1653 | 05:33:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:02 INFO - PROCESS | 1653 | RSS: Main: 202317824 05:33:02 INFO - PROCESS | 1653 | 05:33:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:33:06 INFO - PROCESS | 1653 | RSS: Main: 202342400 05:33:06 INFO - PROCESS | 1653 | 05:33:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:33:09 INFO - PROCESS | 1653 | RSS: Main: 202731520 05:33:09 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 05:33:11 INFO - PROCESS | 1653 | RSS: Main: 204644352 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | __start_tp_report 05:33:11 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 05:33:11 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 05:33:11 INFO - PROCESS | 1653 | |i|pagename|runs| 05:33:11 INFO - PROCESS | 1653 | |0;ai-astar;116;119;122;120;118;115;116;144;170;149 05:33:11 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;133;101;168;138;144;141;112;128;109 05:33:11 INFO - PROCESS | 1653 | |2;audio-dft;191;194;197;205;196;196;195;197;194;198 05:33:11 INFO - PROCESS | 1653 | |3;audio-fft;79;58;60;85;60;59;58;58;70;59 05:33:11 INFO - PROCESS | 1653 | |4;audio-oscillator;86;69;84;71;69;69;80;70;70;80 05:33:11 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;87;88;86;85;132;135;87;92;86 05:33:11 INFO - PROCESS | 1653 | |6;imaging-darkroom;98;136;171;99;98;100;104;100;98;100 05:33:11 INFO - PROCESS | 1653 | |7;imaging-desaturate;92;99;91;91;96;92;89;142;144;94 05:33:11 INFO - PROCESS | 1653 | |8;json-parse-financial;58;56;58;60;60;61;61;61;62;61 05:33:11 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;66;73;71;83;77;74;68;68;96;104 05:33:11 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;84;87;78;71;75;96;103;107;102;85 05:33:11 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;155;135;149;123;141;149;159;135;203;165 05:33:11 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;164;167;167;176;229;168;164;166;163;164 05:33:11 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;66;66;65;66;64;65;66;68;62 05:33:11 INFO - PROCESS | 1653 | __end_tp_report 05:33:11 INFO - PROCESS | 1653 | __start_cc_report 05:33:11 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,940 05:33:11 INFO - PROCESS | 1653 | __end_cc_report 05:33:11 INFO - PROCESS | 1653 | __startTimestamp1490617991856__endTimestamp 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | ------- Summary: start ------- 05:33:11 INFO - PROCESS | 1653 | Number of tests: 14 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:128.90 Median:121.00 stddev:18.83 (15.6%) stddev-sans-first:19.38 05:33:11 INFO - PROCESS | 1653 | Values: 116.0 119.0 122.0 120.0 118.0 115.0 116.0 144.0 170.0 149.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:139.50 stddev:19.84 (14.2%) stddev-sans-first:20.74 05:33:11 INFO - PROCESS | 1653 | Values: 141.0 133.0 101.0 168.0 138.0 144.0 141.0 112.0 128.0 109.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.30 Median:196.50 stddev:3.65 (1.9%) stddev-sans-first:3.33 05:33:11 INFO - PROCESS | 1653 | Values: 191.0 194.0 197.0 205.0 196.0 196.0 195.0 197.0 194.0 198.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:64.60 Median:60.00 stddev:9.94 (16.6%) stddev-sans-first:9.07 05:33:11 INFO - PROCESS | 1653 | Values: 79.0 58.0 60.0 85.0 60.0 59.0 58.0 58.0 70.0 59.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:75.50 stddev:6.88 (9.1%) stddev-sans-first:5.98 05:33:11 INFO - PROCESS | 1653 | Values: 86.0 69.0 84.0 71.0 69.0 69.0 80.0 70.0 70.0 80.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:87.00 stddev:19.46 (22.4%) stddev-sans-first:20.49 05:33:11 INFO - PROCESS | 1653 | Values: 90.0 87.0 88.0 86.0 85.0 132.0 135.0 87.0 92.0 86.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:134.50 stddev:24.23 (18.0%) stddev-sans-first:25.28 05:33:11 INFO - PROCESS | 1653 | Values: 98.0 136.0 171.0 99.0 98.0 100.0 104.0 100.0 98.0 100.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:92.00 stddev:21.28 (23.1%) stddev-sans-first:22.19 05:33:11 INFO - PROCESS | 1653 | Values: 92.0 99.0 91.0 91.0 96.0 92.0 89.0 142.0 144.0 94.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:61.00 stddev:1.87 (3.1%) stddev-sans-first:1.87 05:33:11 INFO - PROCESS | 1653 | Values: 58.0 56.0 58.0 60.0 60.0 61.0 61.0 61.0 62.0 61.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:78.00 Median:73.50 stddev:12.74 (17.3%) stddev-sans-first:12.75 05:33:11 INFO - PROCESS | 1653 | Values: 66.0 73.0 71.0 83.0 77.0 74.0 68.0 68.0 96.0 104.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:81.00 stddev:12.58 (15.5%) stddev-sans-first:13.22 05:33:11 INFO - PROCESS | 1653 | Values: 84.0 87.0 78.0 71.0 75.0 96.0 103.0 107.0 102.0 85.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:152.00 stddev:22.07 (14.5%) stddev-sans-first:23.37 05:33:11 INFO - PROCESS | 1653 | Values: 155.0 135.0 149.0 123.0 141.0 149.0 159.0 135.0 203.0 165.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:167.00 stddev:20.09 (12.0%) stddev-sans-first:21.06 05:33:11 INFO - PROCESS | 1653 | Values: 164.0 167.0 167.0 176.0 229.0 168.0 164.0 166.0 163.0 164.0 05:33:11 INFO - PROCESS | 1653 | 05:33:11 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:1.58 (2.4%) stddev-sans-first:1.66 05:33:11 INFO - PROCESS | 1653 | Values: 66.0 66.0 66.0 65.0 66.0 64.0 65.0 66.0 68.0 62.0 05:33:11 INFO - PROCESS | 1653 | -------- Summary: end -------- 05:33:11 INFO - PROCESS | 1653 | 05:33:12 INFO - TEST-INFO | 1653: exit 0 05:33:12 INFO - TEST-OK | kraken | took 59421ms 05:33:12 INFO - SUITE-END | took 1173s 05:33:13 INFO - Completed test suite (00:19:33) 05:33:13 INFO - javascript benchmark 05:33:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7527.654901785679, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3539.964600353996, 3561.4479755637885, 3565.393883039883, 3576.356255873945, 3579.892603221904, 4933.358663373757, 5928.407159284069, 5983.790567330145, 6023.789167379143, 6040.395960403957, 4421.602055814976, 6049.667268300242, 6050.697465126748, 6077.908831367533, 6095.146679464868, 5341.332333458327, 5412.4587541245955, 5448.482394172547, 5452.754626041838, 5474.671519708811, 4038.676905847525, 4930.901381972376, 5093.2105523643895, 5510.559155267589, 5528.198411230369, 5353.277307563468, 5379.085555455582, 5475.178723191513, 5479.671219726829, 5518.558515318765, 5344.171653393711, 5418.160185171305, 5463.180522921554, 5519.751611177507, 5569.916451253234, 5347.705876176848, 5457.5633949283965, 5473.17902314652, 5479.068558345091, 5507.752151153168, 4881.941604237528, 5751.942480575182, 5970.462658360769, 6098.54260930432, 6137.60105593135, 6260.966940454819, 6301.180846489927, 6303.905441418382, 6338.809835704937, 6353.904691429632, 5423.620346575742, 6381.004639098082, 6419.3259707730485, 6424.293327733945, 6440.581362211488, 6151.015837465984, 6158.969205153946, 6187.5668703190795, 6208.81373558794, 6213.5339849511465, 6255.124282600348, 6260.780872669526, 6399.9360006400275, 6433.260175079832, 6478.255000674889, 6243.843903902348, 6288.654124023223, 6290.339514351019, 6307.653079080695, 6307.905381419282, 6397.584157029778, 6405.711742971577, 6407.231132264158, 6417.807465776034, 6419.358064193637, 6227.564070515021, 6250.312465628777, 6263.686815659199, 6271.749130034838, 6293.30773614902, 6279.874402511924, 6320.778772742932, 6327.557071004985, 6335.018072198816, 6351.888419526565, 5158.74206289684, 5160.509751573594, 5162.5095615916425, 5172.353455818023, 5183.81856635016, 4505.5719706627815, 4535.138323718483, 4553.931691024637, 4624.745638989821, 4681.204195286809, 5978.1929439525975, 6027.336992930774, 6068.271807383142, 6098.878022439526, 6105.633661980295, 6393.296737358887, 6439.162908821823, 6458.773942911976, 6467.805965821033, 6479.514036447285, 5809.186713860063, 5826.737796799154, 5830.183774271605, 5900.4099590041515, 5935.910961335583, 5524.8342549722765, 5531.778728850801, 5532.336119665585, 5545.1682247663175, 5558.749856256478, 6370.267419246846, 6396.584222025554, 6396.712147953353, 6404.48764098871, 6434.485241180788, 6173.41352571505, 6255.687215639109, 6277.121203031488, 6278.340774218641, 6353.237611486651, 6620.238672552526, 6641.468682505291, 6650.800475985729, 6657.900131498128, 6660.6336651484635, 5575.4424557543925, 6359.300476947439, 6366.808995730135, 6372.107904893226, 6385.87228255423, 5191.90969896326, 5911.290645122441, 6194.628322300677, 6392.680365981775, 6454.096426500199, 5023.547880690755, 6255.0617407387435, 6292.119103325446, 6298.433141017331, 6307.7476900925285, 5211.60912931524, 6250.1874756281395, 6271.4669253114, 6289.7169627365965, 6297.370262973666, 6007.249093863267, 6090.23872015998, 6111.113888486234, 6225.688715564113, 6253.593516421418, 6203.658798766111, 6215.689215539295, 6259.280182779039, 6274.59215151032, 6303.936960630518, 6250.343713909974, 6259.467945224706, 6270.686465676789, 6270.717817698306, 6283.8114856554375], "value": 5840.0132093458915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.87909884112514, 499.6933020152303, 503.7355388421081, 514.2363590068749, 537.1942086869694, 35200.0, 41810.95472613186, 41822.95442613936, 41897.79051104744, 41934.58065419352, 43306.91732706676, 43429.565704342946, 43870.34194487085, 43926.7803660982, 44036.0, 9481.47851868147, 9734.123928846402, 9756.560954757053, 11309.943450282737, 11747.647570572897, 11492.827607585892, 11548.249363791369, 11612.477438515245, 11652.650420487356, 11696.7660646787, 11480.425978701032, 11601.361925094116, 11639.301641901513, 11673.299602023862, 11678.357690327028, 11557.76884462306, 11562.306261624246, 11587.478563464621, 11639.941800290944, 11668.766624667544, 11500.137489688306, 11577.363245021521, 11591.884081159165, 11612.593559225386, 11653.883461165364, 38405.0, 38733.8379848605, 38750.41874371887, 38782.8365149046, 38867.0, 3274.3123943971555, 3805.8858234253016, 3866.8066596670055, 3867.207222519377, 3907.2966865964113, 28520.0017999369, 29012.0, 29815.85092074526, 30520.0, 30641.69358306433, 3310.2717402171484, 3390.355832391838, 3502.4221003534385, 3539.9557130646263, 3542.0613537412605, 506951.0, 507706.0, 513143.0, 513668.0, 516700.41649791884, 59170.40829591649, 59566.404335956955, 59585.106223406685, 59647.10529342063, 59682.70158649179, 60150.699246504366, 61738.0739288911, 62075.0, 62096.0, 62450.68774656098, 16233.59416014609, 16485.423010194587, 16997.660046798996, 17036.31854725822, 17086.743698844526, 12041.217320874115, 12322.383880805924, 12964.0, 13060.281684507443, 13245.73508529824, 18269.54326141857, 18308.450746477625, 18311.54221144482, 18332.08339583042, 18353.63292734138, 6410.775622853178, 8047.356211503065, 8169.877451838227, 8182.386321025947, 8233.83532329362, 797.6649807081039, 895.1183084661603, 902.5171533229691, 915.3638221436171, 925.4632313258429, 30424.239394014883, 30646.846765766473, 30878.228044298623, 30910.0726978191, 31071.378572428875, 1090.8745494268103, 1100.4607742206422, 1118.3960661243, 1151.614209239914, 1256.8805963433458, 15099.924500377647, 15605.921970390076, 15854.365825367087, 16205.594860128354, 16299.266533006043, 516192.0, 683107.0, 683514.0, 684272.0, 687603.0, 55199.0, 55498.44501555013, 55786.88426231534, 55885.88228235413, 56224.71887640536, 1449.8332691740375, 1469.1919444305588, 1494.9327280272196, 1499.1005396762073, 1503.9774403383958, 24230.151944682555, 27888.58167127535, 33516.162095947795, 34191.0, 37754.43368349532, 53475.0, 67375.65248694901, 67381.0, 67414.6517069646, 67628.6618566904, 598651.0, 604720.0, 606052.0, 606489.0, 607508.0, 41594.58405415907, 42410.57589424066, 47618.285725714835, 48859.0, 49046.509534904195, 42411.57588424076, 51322.23016654828, 51529.96940061253, 53048.73475632597, 53596.46403536071, 32265.0, 34861.30277394387, 35231.82384088063, 36313.0, 36329.0, 34682.65317346895, 34748.30503389968, 35161.64838351686, 36172.27655446929, 36251.81874090613, 34655.0, 41414.58585414107, 41950.79024604979, 42461.0, 42769.572304277805, 33860.49209261814, 34452.31095378028, 35099.0, 35757.28485430329, 36067.639323606425, 26028.479430410905, 27409.862950685118, 27422.0, 27469.901203952417, 27624.0], "value": 21168.899787715578, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.21796310986707, 401.5161730115211, 402.68389314388213, 404.97570145791263, 428.91850548395803, 13865.792013119812, 20630.6905396419, 21402.46493837655, 21989.560208795836, 22038.559228815415, 21762.99999999996, 21879.562408751815, 22080.006399712012, 22325.330240092822, 22428.551428971412, 5928.0811474221455, 6213.161223234851, 6227.470664993481, 6234.625922444668, 6248.843778905518, 5926.555508336871, 6102.511799056065, 6106.633601983873, 6175.598586091912, 6177.289611694645, 6088.66512341821, 6122.112293717409, 6125.356837532062, 6134.049222370518, 6140.53945954053, 5858.736356863951, 6113.480354169878, 6119.326874043852, 6181.134641150243, 6223.0, 5231.581473482112, 5968.462838344526, 5988.940110598882, 6104.145419641254, 6120.724567394478, 13533.458661653522, 13842.653933651638, 13849.653758656013, 13851.891848652083, 18594.256229750794, 2613.333599932005, 2668.7731542818883, 2683.2755156107937, 2688.1263589333544, 2729.3040274730015, 9136.908630913673, 10674.573017079307, 10813.62152324665, 12825.67935801603, 15209.391624335014, 1589.7138515067284, 1879.5676994291252, 1883.7551118354663, 1892.1485331600697, 1894.4316704988587, 113429.0, 136110.6388936108, 136945.0, 137053.0, 142759.57240427568, 10075.848862267072, 10087.29388942767, 12001.499812523434, 12168.939155304166, 12215.75568488643, 14432.061915975428, 15011.324490397958, 16011.599710007344, 16015.878888477928, 16043.839561604467, 7597.924020759721, 8734.563271836383, 8989.0, 9083.229614967366, 9107.499087550115, 8872.911270887209, 8981.236594889508, 9001.18989290967, 9128.45229286245, 9199.126083022102, 3935.0, 4890.975545122252, 4927.0, 4943.8516844494725, 4962.776675049559, 2076.273304343498, 2105.5683584865064, 2107.683847422868, 2111.1133324003954, 2116.693079503494, 1043.9217058720626, 1051.3113910388709, 1053.3311347294523, 1065.211743309945, 1072.3726619927274, 21563.89218053931, 21862.0, 21876.0, 21931.723313953946, 21949.451263718536, 1083.1172594335592, 1088.8584484017026, 1093.6336327330434, 1117.7038084907506, 1131.547381047571, 10526.684199474028, 11216.943915280372, 11345.602903898323, 11497.0, 11541.76916461666, 781720.0, 790997.0, 794305.0, 807103.0, 825734.0, 161793.0, 162160.99999999764, 165258.3474165267, 168673.3132668682, 171001.0, 1020.5968642386216, 1032.4063663393579, 1058.2539309786582, 1062.208654552348, 1063.335415365381, 18682.906585466986, 19085.04574771228, 19319.874813770755, 19392.806071939664, 19399.806001940364, 49994.500054998985, 62758.0, 62797.05804413029, 64451.67774161099, 65145.0, 90438.09561904297, 91720.54139729525, 91888.54055729946, 95273.0, 97472.5126374392, 6793.72825086991, 8501.447405918596, 8656.956715216636, 8706.521141337287, 8745.431546949429, 11214.83177752318, 12117.636470905887, 12711.919486843723, 12900.93549532284, 13072.411741471848, 10659.25385223027, 11477.82783258235, 11535.01952334061, 11560.826587601025, 11662.300261984308, 9445.433274003582, 10462.0, 10613.681589552325, 10706.464676765974, 10977.17671174681, 9826.9017309826, 10075.546600402853, 11706.765864682488, 11948.820767688318, 11979.460924258256, 7432.584410066642, 8407.789805254917, 8555.144485551395, 8612.397132200937, 8727.170918762708, 10867.293625914606, 11612.954834064636, 11846.466908988943, 11906.107041971714, 11908.166428349932], "value": 10072.192738802209, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.342795600047, 1485.7102864941335, 1495.0730547060816, 1498.9475368362107, 1501.632100135467, 7686.0, 7919.366450683946, 8148.877766833502, 8583.098774628668, 8639.43843650163, 7164.319389657987, 8127.146649601796, 8418.494890306585, 8420.157984201576, 8512.914870851306, 2374.8693821839793, 2401.6997875265592, 2404.5311164322966, 2409.156795121702, 2412.589859723851, 2374.169040835702, 2383.392234980078, 2389.0921449849034, 2423.8909249083745, 2428.2836563213896, 2367.597508423572, 2377.072941552796, 2382.03527571333, 2387.415083304584, 2395.964060539093, 2335.1126571902737, 2340.918067867625, 2342.648602709607, 2362.9527409451803, 2363.5745565798234, 2393.186316652329, 2394.736578976311, 2406.5307265083316, 2413.4690368118986, 2413.54142712884, 8459.53472559009, 8519.318454523624, 8541.188587084218, 8551.444156129894, 8604.48373097616, 5212.843614691566, 5346.385165705955, 5354.544863686591, 5363.678179309254, 5378.247045413606, 3599.8560057597665, 3614.9277014459826, 3642.8907132786057, 3652.1782598915297, 3668.853245870162, 1126.9887301126967, 1262.6085913366794, 1272.274803362084, 1292.877176668215, 1317.0, 8928.553572321358, 9657.661981830603, 10885.782284354347, 10958.506867190996, 10970.451477426177, 4471.7987690554, 4730.929036064462, 4740.597049250783, 4773.331733557305, 4778.522147785194, 8699.913000870038, 8940.642374305085, 9525.857112143323, 9634.470104144199, 9674.806503870024, 5313.30926979498, 5341.626086174008, 5372.489613486712, 5381.031414345455, 5396.0, 4448.977755111268, 4487.88780280489, 4888.120138375127, 4923.852284431473, 4945.703257804544, 3963.4253033310583, 4041.2725709372594, 4076.7553946763287, 4105.753654780723, 4108.4864391951005, 1321.7554752370843, 1327.0909427042507, 1328.249539010456, 1337.77257866163, 1341.3092257487401, 860.5138096975189, 925.0379605210641, 936.6253498600478, 941.6939494664125, 977.2768151567789, 5006.249062640561, 5603.411641777555, 5625.465580769902, 6001.279846418458, 6028.758849646053, 968.6754937096151, 1034.7568321444455, 1040.7710303733168, 1049.5172220778527, 1107.5735841700841, 3431.9828400858337, 3940.408938659224, 4051.2100140472326, 4070.0231944333145, 4145.191687620945, 9992.65025724096, 10142.746431339128, 10190.184785217165, 10297.845532317022, 10323.380597164194, 9965.0, 9990.100890919852, 10169.0, 10278.22913281507, 10279.229057820694, 8043.839123217503, 8071.878921816177, 8108.189181081844, 8133.877991830127, 8205.58972051395, 5755.683437410982, 5782.797602083907, 5785.681787501728, 5854.443827836349, 5933.525317974465, 9242.768930776785, 9278.860817087883, 9300.674476393293, 9329.72010839676, 9403.106704863027, 9418.246540276625, 9482.336236463381, 9523.28575356837, 9546.761330966781, 9596.0, 6360.745570177326, 6554.31179726141, 6740.797776066725, 6807.9319206807295, 6948.6178260196175, 6890.20762612306, 7995.680172793023, 8228.382871284797, 8232.17678232172, 8261.339092872677, 7010.719571217094, 7111.964440177766, 7128.0, 7141.500094993508, 7235.63821808918, 7012.368886800213, 7023.262557431396, 7050.506464547638, 7053.470989675692, 7134.32223938725, 6531.0, 6533.901991470227, 6592.967035164794, 6595.439387651912, 6607.372299631527, 5157.974210128926, 5161.690298582097, 5201.869953251199, 5222.8955420892125, 5233.525335837804, 5181.740912954413, 5221.05810113362, 5265.631405801557, 5277.947220527899, 5340.946590534045], "value": 4535.026466198162, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.761237583195, 367.62502247707334, 376.7231085152412, 380.92191100824334, 382.09824813440275, 4336.86989390318, 4928.260760885869, 5082.466341034194, 5166.4316925138255, 5451.412302390042, 4149.087200815823, 5172.689638621685, 5272.446393128724, 5374.811881584145, 5394.163904594784, 4196.97901510492, 4302.494127055536, 4336.696431249814, 4343.501491985276, 4366.759828209447, 3903.63829882921, 3955.3473676843287, 4101.882569247378, 4233.216854881842, 4427.358033085216, 3950.6246906543975, 4281.229378711831, 4286.892827679318, 4294.312909934411, 4337.240982827992, 3591.455674060166, 4257.701960862741, 4286.271333873234, 4319.82720691172, 4343.370211319341, 3834.2523207974455, 3838.9634798604498, 3844.019774957396, 3864.845406183749, 3888.008557817767, 5118.948810511884, 5275.314209152824, 5305.522502974751, 5373.3014708088085, 5376.973115134439, 4698.6241100712205, 4966.925496117561, 4985.975070124662, 4994.525520075587, 5006.974965125188, 4592.609628181609, 5122.9262442534555, 5749.8275051748515, 5835.891180675658, 5877.38287479817, 4179.101493178981, 4196.412502249687, 4205.285101532747, 4208.368744688321, 4276.807543660543, 5262.394824595182, 5289.471052894718, 5941.336425800782, 6121.3572574879445, 6137.907931380988, 3422.8117453540294, 3619.2761447710564, 3739.8878033659034, 4111.671066314688, 4354.934675979863, 3565.8751943681846, 4279.679024073144, 4333.22673488961, 4368.825246990148, 4374.496932852762, 4966.230234313687, 4983.651144419856, 4989.25161225819, 5008.524190201925, 5010.949890501049, 5076.619253555924, 5454.400015998237, 5912.3496415394275, 5912.881742365129, 5927.762889484372, 1339.8745054154556, 1409.2530958591963, 1427.8143841300564, 1429.434768928022, 1431.6921861799763, 917.655879045358, 958.9616415343309, 965.1024547171058, 981.293468702533, 1002.5338217728792, 4776.880577985578, 4954.281629163823, 5091.719955402415, 5111.747621832675, 5170.93162734909, 4967.081089998362, 5645.943540564624, 5695.316562012502, 5782.913256301159, 5800.709964501758, 4209.5579964103945, 4720.811167553259, 4805.8946442317765, 4988.152014157602, 5167.405748338989, 2513.416547575016, 2649.523085844528, 3005.2637103909683, 3013.9903132450872, 3016.5173572228337, 4912.59990902591, 5169.353830771153, 5461.8152738904755, 5769.788344447629, 5955.642661440327, 5032.6980381177245, 5196.948030519722, 5901.704914754245, 5974.2233509643465, 5986.042233242747, 4879.731614761222, 5904.08486684556, 5932.775209296514, 5947.888499582672, 5976.342602313829, 5064.974675126601, 5401.405845357086, 5402.6758394496455, 5461.508464238238, 5702.486776190163, 5315.468453154808, 5638.718064096861, 5694.17434471991, 5709.714514274353, 5723.883842650643, 5361.758720857649, 5377.489138531833, 5717.971410142923, 5887.41125887425, 5958.59972906358, 3756.6431189037, 4119.134981653886, 4145.0, 4156.605122513356, 4256.616904478612, 5024.71375524143, 5481.808136715196, 5961.970190149022, 5976.579485308457, 6022.1870047543025, 5821.952048631118, 5834.562407819346, 5842.269716285465, 5851.590388672922, 5884.616730224266, 4502.887427814331, 5490.313710786152, 5738.614217611629, 5783.298688839062, 5792.797252096157, 5181.3005244291535, 5310.751973286197, 5641.830745077655, 5676.148577713376, 5691.459311365414, 5265.815696450606, 5316.0, 5578.940001399721, 5793.246877905845, 5818.883622327445, 5268.736563171749, 5417.891642167214, 5612.747426365741, 5649.887002260014, 5698.8290351289525], "value": 4228.737984420406, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.7749965011898, 733.6295170938675, 735.6211551051206, 854.8204876975835, 861.8491763941313, 15199.240037998097, 15793.60515987101, 15924.840751592481, 16075.758863617055, 16231.188440577967, 14787.778183327258, 14796.334164962576, 14821.412577251875, 15219.162946037963, 15301.311440985155, 886.9911300887005, 889.995550022249, 904.4754042655244, 905.5562774240641, 921.8294615496121, 739.8779201431757, 905.4386280506062, 905.7373361725092, 907.9591418386156, 908.6229214875851, 929.330881765128, 942.2744486745237, 959.395580784105, 959.4531117263163, 960.279790157382, 929.4330458420358, 947.9052094790535, 951.5860600638745, 952.6808519146094, 953.3374304858127, 708.88303429934, 710.4103594017005, 715.9427245820322, 717.8133685241822, 718.6550455781254, 15680.294386752623, 15728.842711572966, 15815.446459373867, 16449.0, 16768.413105541247, 14588.27058647074, 14603.342849571794, 14805.520702634863, 14834.0, 14941.103533788008, 11916.761664766656, 12049.0, 12101.939490302491, 12116.212446191057, 12140.028797696163, 3716.981415092934, 3834.884953451401, 3845.8846234613006, 3877.2051729395407, 4003.5796241394523, 87487.0, 109098.0, 109326.0, 111751.44124279408, 113641.0, 10899.945500272606, 10978.615748448925, 11309.0, 11336.0, 11590.188686791844, 22703.772962270497, 23097.88451057734, 23570.528589428115, 23574.29277121689, 23620.881895590413, 7985.241402066795, 8023.518588884685, 8028.718994835152, 8163.38774591908, 8234.835303293901, 4387.780610969438, 4405.757683327383, 4471.19518486669, 4472.2620767573635, 4478.081993191388, 3739.364308067635, 3798.0504873781556, 3815.980920095382, 3818.3317919364, 3849.9230015399535, 684.7295318349225, 690.751603530515, 702.4871843554132, 704.1163340008359, 711.9394851437582, 676.4791110844623, 687.7180356053993, 713.9535930164523, 734.9412047036225, 765.4259305520859, 9623.278254130968, 9911.65309214174, 10340.224483163793, 10887.510062047228, 11032.724181895517, 596.880623875218, 680.8502129531495, 690.775497963154, 710.8649356622225, 727.1310783613636, 5055.317532133115, 5166.302549155891, 5195.506426889515, 5212.244224587414, 5222.0, 105418.0, 105631.4718426403, 109090.0, 110132.4493377544, 112590.0, 94795.0, 96237.0376296242, 97496.0, 98658.52012219823, 99575.0, 85430.0, 85870.57064714636, 87086.56456717802, 87628.0, 88811.66782498138, 8124.918750812652, 8422.663093476192, 9908.306418550921, 9920.107190352903, 9992.950035249778, 44558.77720611376, 50557.49442505528, 55479.445205547425, 56473.71763141158, 57173.42826571681, 54859.72570137124, 73044.26955730375, 74810.0, 75382.62308688421, 76120.23879761131, 10528.94735526348, 11858.466369013588, 11974.760504790029, 12012.159148859497, 12147.757044858876, 12947.805782913452, 13175.868241317465, 13291.601251962456, 13316.134451260637, 13458.394372253468, 12057.81913271284, 12404.441800119197, 12566.937165314115, 12614.936925315315, 12791.0, 12327.93836030814, 12377.133600647869, 12386.876131238572, 12677.239365637723, 12854.228746274879, 11122.0, 11338.716532086764, 11930.582429614922, 12030.939845300718, 12084.818727718915, 8882.600282987152, 8940.776480587778, 9295.581698823704, 9429.764255893657, 9501.904980950101, 8358.749237522885, 8541.786455338666, 9494.76872934846, 10274.537645805807, 10277.74305642365], "value": 7618.757726205663, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 119.0, 122.0, 120.0, 118.0, 115.0, 116.0, 144.0, 170.0, 149.0], "value": 128.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 101.0, 168.0, 138.0, 144.0, 141.0, 112.0, 128.0, 109.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 194.0, 197.0, 205.0, 196.0, 196.0, 195.0, 197.0, 194.0, 198.0], "value": 196.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 85.0, 60.0, 59.0, 58.0, 58.0, 70.0, 59.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 84.0, 71.0, 69.0, 69.0, 80.0, 70.0, 70.0, 80.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 86.0, 85.0, 132.0, 135.0, 87.0, 92.0, 86.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 136.0, 171.0, 99.0, 98.0, 100.0, 104.0, 100.0, 98.0, 100.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 91.0, 91.0, 96.0, 92.0, 89.0, 142.0, 144.0, 94.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 60.0, 60.0, 61.0, 61.0, 61.0, 62.0, 61.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 71.0, 83.0, 77.0, 74.0, 68.0, 68.0, 96.0, 104.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 78.0, 71.0, 75.0, 96.0, 103.0, 107.0, 102.0, 85.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 135.0, 149.0, 123.0, 141.0, 149.0, 159.0, 135.0, 203.0, 165.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 167.0, 176.0, 229.0, 168.0, 164.0, 166.0, 163.0, 164.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 64.0, 65.0, 66.0, 68.0, 62.0], "value": 65.4, "unit": "score"}]}]} 05:33:13 INFO - Return code: 0 05:33:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:33:13 ERROR - # TBPL SUCCESS # 05:33:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:13 INFO - 2017-03-27 05:33:13.198 screenresolution[1658:10487] starting screenresolution argv=screenresolution get 05:33:13 INFO - 2017-03-27 05:33:13.200 screenresolution[1658:10487] Display 0: 1600x1200x32@60 05:33:13 INFO - 2017-03-27 05:33:13.206 screenresolution[1659:10490] starting screenresolution argv=screenresolution list 05:33:13 INFO - Available Modes on Display 0 05:33:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:13 INFO - Intel Iris: 05:33:13 INFO - Chipset Model: Intel Iris 05:33:13 INFO - Type: GPU 05:33:13 INFO - Bus: Built-In 05:33:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:13 INFO - Vendor: Intel (0x8086) 05:33:13 INFO - Device ID: 0x0a2e 05:33:13 INFO - Revision ID: 0x0009 05:33:13 INFO - Displays: 05:33:13 INFO - Display: 05:33:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:13 INFO - Display Serial Number: 1600x1200 60 05:33:13 INFO - Main Display: Yes 05:33:13 INFO - Mirror: Off 05:33:13 INFO - Online: Yes 05:33:13 INFO - Rotation: Supported 05:33:13 INFO - Return code: 0 05:33:13 INFO - Running post-action listener: _resource_record_post_action 05:33:13 INFO - [mozharness: 2017-03-27 12:33:13.442885Z] Finished run-tests step (success) 05:33:13 INFO - Running post-run listener: _resource_record_post_run 05:33:13 INFO - Total resource usage - Wall time: 1189s; CPU: 54.0%; Read bytes: 39458816; Write bytes: 424453632; Read time: 245; Write time: 1406 05:33:13 INFO - TinderboxPrint: CPU usage
54.4% 05:33:13 INFO - TinderboxPrint: I/O read bytes / time
39,458,816 / 245 05:33:13 INFO - TinderboxPrint: I/O write bytes / time
424,453,632 / 1,406 05:33:13 INFO - TinderboxPrint: CPU idle
2,170.7 (45.6%) 05:33:13 INFO - TinderboxPrint: CPU system
958.1 (20.1%) 05:33:13 INFO - TinderboxPrint: CPU user
1,628.9 (34.2%) 05:33:13 INFO - TinderboxPrint: Swap in / out
175,886,336 / 0 05:33:13 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 113048064; Write bytes: 111521792; Read time: 10072; Write time: 236 05:33:13 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 38537216; Write bytes: 300843008; Read time: 234; Write time: 1132 05:33:13 INFO - Running post-run listener: _upload_blobber_files 05:33:13 INFO - Blob upload gear active. 05:33:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:13 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 05:33:13 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'] 05:33:13 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 05:33:14 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:33:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:33:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:15 INFO - (blobuploader) - INFO - Done attempting. 05:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:33:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:16 INFO - (blobuploader) - INFO - Done attempting. 05:33:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:33:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:33:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:17 INFO - (blobuploader) - INFO - Done attempting. 05:33:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:33:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:33:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:19 INFO - (blobuploader) - INFO - Done attempting. 05:33:19 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:19 INFO - Return code: 0 05:33:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87fc5e6ac6f24ea113781edd8da1014057c3932b6ecbb54e6e84f69608b9ae689844d9f6874f918ce300673a396c0322e01aa5a8a704afff275e01c991328e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5aa60bd2264a5aa1511a31ea56253fc521592da33b3498311a7f41dbe2a7c73a6f7cc93f57279f44540c91dbad61f7e4f198127c274af42e3d3e8da8d50fc87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7b25a18a93bda43b589227c1fe5eb843da2b2c854a329a81e1cd006224db3fb78f346b3674e1666bdb68aa63bc2dd4dfcf229e62802d55dbaf7f4f62ea795a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20058150c8573d56f2909cc5756c27e071518abdd5e07c05fb893a88edfaf1decaa6f33ef7b04561b7779b7a3e7954769ec2988a799ec9d1d5c7f972425be099"} 05:33:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:19 INFO - Contents: 05:33:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87fc5e6ac6f24ea113781edd8da1014057c3932b6ecbb54e6e84f69608b9ae689844d9f6874f918ce300673a396c0322e01aa5a8a704afff275e01c991328e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5aa60bd2264a5aa1511a31ea56253fc521592da33b3498311a7f41dbe2a7c73a6f7cc93f57279f44540c91dbad61f7e4f198127c274af42e3d3e8da8d50fc87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7b25a18a93bda43b589227c1fe5eb843da2b2c854a329a81e1cd006224db3fb78f346b3674e1666bdb68aa63bc2dd4dfcf229e62802d55dbaf7f4f62ea795a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20058150c8573d56f2909cc5756c27e071518abdd5e07c05fb893a88edfaf1decaa6f33ef7b04561b7779b7a3e7954769ec2988a799ec9d1d5c7f972425be099"} 05:33:19 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:19 INFO - Copying logs to upload dir... 05:33:19 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.229682 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-27 05:33:19.871490) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:33:19.874820) ========= 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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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/87fc5e6ac6f24ea113781edd8da1014057c3932b6ecbb54e6e84f69608b9ae689844d9f6874f918ce300673a396c0322e01aa5a8a704afff275e01c991328e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5aa60bd2264a5aa1511a31ea56253fc521592da33b3498311a7f41dbe2a7c73a6f7cc93f57279f44540c91dbad61f7e4f198127c274af42e3d3e8da8d50fc87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7b25a18a93bda43b589227c1fe5eb843da2b2c854a329a81e1cd006224db3fb78f346b3674e1666bdb68aa63bc2dd4dfcf229e62802d55dbaf7f4f62ea795a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20058150c8573d56f2909cc5756c27e071518abdd5e07c05fb893a88edfaf1decaa6f33ef7b04561b7779b7a3e7954769ec2988a799ec9d1d5c7f972425be099"} build_url:https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011115 build_url: 'https://queue.taskcluster.net/v1/task/X-R8vEb_RxOXAe7sA6T-_g/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/87fc5e6ac6f24ea113781edd8da1014057c3932b6ecbb54e6e84f69608b9ae689844d9f6874f918ce300673a396c0322e01aa5a8a704afff275e01c991328e0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5aa60bd2264a5aa1511a31ea56253fc521592da33b3498311a7f41dbe2a7c73a6f7cc93f57279f44540c91dbad61f7e4f198127c274af42e3d3e8da8d50fc87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7b25a18a93bda43b589227c1fe5eb843da2b2c854a329a81e1cd006224db3fb78f346b3674e1666bdb68aa63bc2dd4dfcf229e62802d55dbaf7f4f62ea795a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20058150c8573d56f2909cc5756c27e071518abdd5e07c05fb893a88edfaf1decaa6f33ef7b04561b7779b7a3e7954769ec2988a799ec9d1d5c7f972425be099"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:33:19.910058) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:33:19.910424) ========= 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.bxZlVnMzRb/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.Pn0OIwQBCu/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:33:19.959441) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:33:19.959778) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:33:19.960131) ========= ========= Total master_lag: 0.23 =========