builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1492791806.66 results: success (0) buildid: 20170421075927 builduid: 971f8957d79c4cf88040baaec9929424 revision: 6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.657967) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.658428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.658741) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.686186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.686584) ========= 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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-04-21 09:23:26-- 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% 13.2M=0.001s 2017-04-21 09:23:26 (13.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131100 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.841149) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.841461) ========= 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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.030510 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:26.894396) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 09:23:26.894785) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7 --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 6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jVqEITsCVH/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.r4uUMVhmPJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-04-21 09:23:26,998 truncating revision to first 12 chars 2017-04-21 09:23:26,998 Setting DEBUG logging. 2017-04-21 09:23:26,999 attempt 1/10 2017-04-21 09:23:26,999 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6ef4b8dc8d1a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-21 09:23:28,435 unpacking tar archive at: mozilla-beta-6ef4b8dc8d1a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.698169 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 09:23:28.617881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:28.618199) ========= script_repo_revision: 6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:28.618592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:28.618868) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 09:23:28.639880) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-04-21 09:23:28.640268) ========= /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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:23:28 INFO - MultiFileLogger online at 20170421 09:23:28 in /builds/slave/test 09:23:28 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 09:23:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:23:28 INFO - {'append_to_log': False, 09:23:28 INFO - 'base_work_dir': '/builds/slave/test', 09:23:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:23:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:23:28 INFO - 'branch': 'Mozilla-Beta', 09:23:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:23:28 INFO - 'config_files': ('talos/mac_config.py',), 09:23:28 INFO - 'default_actions': ('clobber', 09:23:28 INFO - 'read-buildbot-config', 09:23:28 INFO - 'download-and-extract', 09:23:28 INFO - 'populate-webroot', 09:23:28 INFO - 'create-virtualenv', 09:23:28 INFO - 'install', 09:23:28 INFO - 'run-tests'), 09:23:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:23:28 INFO - 'download_minidump_stackwalk': True, 09:23:28 INFO - 'download_symbols': 'ondemand', 09:23:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:23:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:23:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:23:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:23:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:23:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:23:28 INFO - 'gecko_profile': False, 09:23:28 INFO - 'gecko_profile_interval': 0, 09:23:28 INFO - 'installer_path': 'installer.exe', 09:23:28 INFO - 'log_level': 'info', 09:23:28 INFO - 'log_name': 'talos', 09:23:28 INFO - 'log_to_console': True, 09:23:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:23:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:23:28 INFO - 'opt_config_files': (), 09:23:28 INFO - 'pip_index': False, 09:23:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:28 INFO - 'cmd': ('bash', 09:23:28 INFO - '-c', 09:23:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:28 INFO - 'enabled': True, 09:23:28 INFO - 'halt_on_failure': False, 09:23:28 INFO - 'name': 'check_screen_resolution'},), 09:23:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:23:28 INFO - 'cmd': ('bash', 09:23:28 INFO - '-c', 09:23:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:23:28 INFO - 'enabled': True, 09:23:28 INFO - 'halt_on_failure': False, 09:23:28 INFO - 'name': 'check_screen_resolution'},), 09:23:28 INFO - 'run_cmd_checks_enabled': True, 09:23:28 INFO - 'suite': 'dromaeojs-e10s', 09:23:28 INFO - 'system_bits': '32', 09:23:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:23:28 INFO - 'title': 't-yosemite-r7-0086', 09:23:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:23:28 INFO - 'use_talos_json': True, 09:23:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:23:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:23:28 INFO - 'work_dir': 'build'} 09:23:28 INFO - [mozharness: 2017-04-21 16:23:28.860655Z] Running clobber step. 09:23:28 INFO - Running pre-action listener: _resource_record_pre_action 09:23:28 INFO - Running main action method: clobber 09:23:28 INFO - rmtree: /builds/slave/test/build 09:23:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:23:31 INFO - Running post-action listener: _resource_record_post_action 09:23:31 INFO - [mozharness: 2017-04-21 16:23:31.458300Z] Finished clobber step (success) 09:23:31 INFO - [mozharness: 2017-04-21 16:23:31.458427Z] Running read-buildbot-config step. 09:23:31 INFO - Running pre-action listener: _resource_record_pre_action 09:23:31 INFO - Running main action method: read_buildbot_config 09:23:31 INFO - Using buildbot properties: 09:23:31 INFO - { 09:23:31 INFO - "project": "", 09:23:31 INFO - "product": "firefox", 09:23:31 INFO - "script_repo_revision": "production", 09:23:31 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:23:31 INFO - "repository": "", 09:23:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:23:31 INFO - "buildid": "20170421075927", 09:23:31 INFO - "pgo_build": "False", 09:23:31 INFO - "basedir": "/builds/slave/test", 09:23:31 INFO - "buildnumber": 8, 09:23:31 INFO - "slavename": "t-yosemite-r7-0086", 09:23:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:23:31 INFO - "platform": "yosemite_r7", 09:23:31 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:23:31 INFO - "branch": "mozilla-beta", 09:23:31 INFO - "repo_path": "releases/mozilla-beta", 09:23:31 INFO - "revision": "6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7", 09:23:31 INFO - "stage_platform": "macosx64", 09:23:31 INFO - "builduid": "971f8957d79c4cf88040baaec9929424", 09:23:31 INFO - "slavebuilddir": "test" 09:23:31 INFO - } 09:23:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:23:31 INFO - Running post-action listener: _resource_record_post_action 09:23:31 INFO - [mozharness: 2017-04-21 16:23:31.461806Z] Finished read-buildbot-config step (success) 09:23:31 INFO - [mozharness: 2017-04-21 16:23:31.461919Z] Running download-and-extract step. 09:23:31 INFO - Running pre-action listener: _resource_record_pre_action 09:23:31 INFO - Running main action method: download_and_extract 09:23:31 INFO - mkdir: /builds/slave/test/build/tests 09:23:31 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')]} 09:23:31 INFO - trying https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:23:31 INFO - Downloading https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:23:32 INFO - Downloaded 1165 bytes. 09:23:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:23:32 INFO - Using the following test package requirements: 09:23:32 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:23:32 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:23:32 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:23:32 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:23:32 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:23:32 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:23:32 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:23:32 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:23:32 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:23:32 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:23:32 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:23:32 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:23:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:23:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:23:32 INFO - Fetch https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:23:34 INFO - Expected file size: 51221131 09:23:34 INFO - Obtained file size: 51221131 09:23:50 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:23:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:23:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:23:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:23:52 INFO - Expected file size: 51221131 09:23:52 INFO - Obtained file size: 51221131 09:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:24:08 INFO - Expected file size: 14404424 09:24:08 INFO - Obtained file size: 14404424 09:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:08 INFO - trying https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:24:08 INFO - Downloading https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:24:10 INFO - Downloaded 59544023 bytes. 09:24:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:24:10 INFO - mkdir: /builds/slave/test/properties 09:24:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:24:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:24:10 INFO - Contents: 09:24:10 INFO - build_url:https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:24:10 INFO - Running post-action listener: _resource_record_post_action 09:24:10 INFO - Running post-action listener: set_extra_try_arguments 09:24:10 INFO - [mozharness: 2017-04-21 16:24:10.956332Z] Finished download-and-extract step (success) 09:24:10 INFO - [mozharness: 2017-04-21 16:24:10.956484Z] Running populate-webroot step. 09:24:10 INFO - Running pre-action listener: _resource_record_pre_action 09:24:10 INFO - Running main action method: populate_webroot 09:24:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:24:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:24:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:24:10 INFO - '--tppagecycles', 09:24:10 INFO - '7'], 09:24:10 INFO - 'tests': ['tsvgm']}}, 09:24:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:24:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:24:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:24:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:24:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['damp', 'tps']}, 09:24:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'tests': ['damp', 'tps']}, 09:24:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['dromaeo_dom']}, 09:24:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:24:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['basic_compositor_video']}, 09:24:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:24:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['a11yr', 09:24:10 INFO - 'ts_paint', 09:24:10 INFO - 'tpaint', 09:24:10 INFO - 'sessionrestore', 09:24:10 INFO - 'sessionrestore_no_auto_restore', 09:24:10 INFO - 'tabpaint']}, 09:24:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:24:10 INFO - 'ts_paint', 09:24:10 INFO - 'tpaint', 09:24:10 INFO - 'sessionrestore', 09:24:10 INFO - 'sessionrestore_no_auto_restore', 09:24:10 INFO - 'tabpaint']}, 09:24:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:24:10 INFO - 'ts_paint', 09:24:10 INFO - 'tpaint', 09:24:10 INFO - 'sessionrestore', 09:24:10 INFO - 'sessionrestore_no_auto_restore', 09:24:10 INFO - 'tabpaint']}, 09:24:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:24:10 INFO - 'ts_paint', 09:24:10 INFO - 'tpaint', 09:24:10 INFO - 'sessionrestore', 09:24:10 INFO - 'sessionrestore_no_auto_restore', 09:24:10 INFO - 'tabpaint']}, 09:24:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['a11yr', 09:24:10 INFO - 'ts_paint', 09:24:10 INFO - 'tpaint', 09:24:10 INFO - 'sessionrestore', 09:24:10 INFO - 'sessionrestore_no_auto_restore', 09:24:10 INFO - 'tabpaint']}, 09:24:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['a11yr', 09:24:10 INFO - 'ts_paint', 09:24:10 INFO - 'tpaint', 09:24:10 INFO - 'sessionrestore', 09:24:10 INFO - 'sessionrestore_no_auto_restore', 09:24:10 INFO - 'tabpaint']}, 09:24:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['tsvgx', 09:24:10 INFO - 'tsvgr_opacity', 09:24:10 INFO - 'tart', 09:24:10 INFO - 'tscrollx', 09:24:10 INFO - 'cart', 09:24:10 INFO - 'tsvg_static']}, 09:24:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:24:10 INFO - 'tsvgr_opacity', 09:24:10 INFO - 'tart', 09:24:10 INFO - 'tscrollx', 09:24:10 INFO - 'cart', 09:24:10 INFO - 'tsvg_static']}, 09:24:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'talos_options': ['--disable-e10s'], 09:24:10 INFO - 'tests': ['tp5o']}, 09:24:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:24:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'talos_options': ['--disable-e10s', 09:24:10 INFO - '--xperf_path', 09:24:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:10 INFO - 'tests': ['tp5n']}, 09:24:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:24:10 INFO - 'talos_options': ['--xperf_path', 09:24:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:10 INFO - 'tests': ['tp5n']}}, 09:24:10 INFO - 'talos.zip': {'path': '', 09:24:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:24:10 INFO - Running post-action listener: _resource_record_post_action 09:24:10 INFO - [mozharness: 2017-04-21 16:24:10.974892Z] Finished populate-webroot step (success) 09:24:10 INFO - [mozharness: 2017-04-21 16:24:10.975027Z] Running create-virtualenv step. 09:24:10 INFO - Running pre-action listener: _resource_record_pre_action 09:24:10 INFO - Running main action method: create_virtualenv 09:24:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:24:10 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 09:24:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:24:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:24:11 INFO - Using real prefix '/tools/python27' 09:24:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:24:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:14 INFO - Installing pip.................done. 09:24:14 INFO - Return code: 0 09:24:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:24:14 INFO - Reading from file tmpfile_stdout 09:24:14 INFO - Output received: 09:24:14 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) 09:24:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:14 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')]} 09:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:14 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')]} 09:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:14 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:14 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 09:24:14 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 09:24:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:14 INFO - 'HOME': '/Users/cltbld', 09:24:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:14 INFO - 'LOGNAME': 'cltbld', 09:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:14 INFO - 'MOZ_NO_REMOTE': '1', 09:24:14 INFO - 'NO_EM_RESTART': '1', 09:24:14 INFO - 'PAGER': '/bin/cat', 09:24:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:14 INFO - 'PWD': '/builds/slave/test', 09:24:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:14 INFO - 'SHELL': '/bin/bash', 09:24:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:14 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:14 INFO - 'USER': 'cltbld', 09:24:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:14 INFO - 'XPC_FLAGS': '0x0', 09:24:14 INFO - 'XPC_SERVICE_NAME': '0', 09:24:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:15 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 09:24:15 INFO - Cleaning up... 09:24:15 INFO - Return code: 0 09:24:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:15 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:15 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 09:24:15 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 09:24:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:15 INFO - 'HOME': '/Users/cltbld', 09:24:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:15 INFO - 'LOGNAME': 'cltbld', 09:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:15 INFO - 'MOZ_NO_REMOTE': '1', 09:24:15 INFO - 'NO_EM_RESTART': '1', 09:24:15 INFO - 'PAGER': '/bin/cat', 09:24:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:15 INFO - 'PWD': '/builds/slave/test', 09:24:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:15 INFO - 'SHELL': '/bin/bash', 09:24:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:15 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:15 INFO - 'USER': 'cltbld', 09:24:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:15 INFO - 'XPC_FLAGS': '0x0', 09:24:15 INFO - 'XPC_SERVICE_NAME': '0', 09:24:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:15 INFO - Downloading/unpacking psutil>=3.1.1 09:24:15 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 09:24:15 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 09:24:15 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 09:24:15 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 09:24:15 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 09:24:15 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 09:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:19 INFO - Installing collected packages: psutil 09:24:19 INFO - Running setup.py install for psutil 09:24:19 INFO - building 'psutil._psutil_osx' extension 09:24:19 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 09:24:19 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 09:24:19 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 09:24:20 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 09:24:20 INFO - building 'psutil._psutil_posix' extension 09:24:20 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 09:24:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:24:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:24:20 INFO - ^ 09:24:20 INFO - 1 warning generated. 09:24:20 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 09:24:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:20 INFO - Successfully installed psutil 09:24:20 INFO - Cleaning up... 09:24:20 INFO - Return code: 0 09:24:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:20 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:20 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 09:24:20 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 09:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:20 INFO - 'HOME': '/Users/cltbld', 09:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:20 INFO - 'LOGNAME': 'cltbld', 09:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:20 INFO - 'MOZ_NO_REMOTE': '1', 09:24:20 INFO - 'NO_EM_RESTART': '1', 09:24:20 INFO - 'PAGER': '/bin/cat', 09:24:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:20 INFO - 'PWD': '/builds/slave/test', 09:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:20 INFO - 'SHELL': '/bin/bash', 09:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:20 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:20 INFO - 'USER': 'cltbld', 09:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:20 INFO - 'XPC_FLAGS': '0x0', 09:24:20 INFO - 'XPC_SERVICE_NAME': '0', 09:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:20 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 09:24:20 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 09:24:20 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 09:24:20 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 09:24:20 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 09:24:20 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 09:24:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:23 INFO - Installing collected packages: mozsystemmonitor 09:24:23 INFO - Running setup.py install for mozsystemmonitor 09:24:23 INFO - Successfully installed mozsystemmonitor 09:24:23 INFO - Cleaning up... 09:24:23 INFO - Return code: 0 09:24:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:24:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:24 INFO - 'HOME': '/Users/cltbld', 09:24:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:24 INFO - 'LOGNAME': 'cltbld', 09:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:24 INFO - 'MOZ_NO_REMOTE': '1', 09:24:24 INFO - 'NO_EM_RESTART': '1', 09:24:24 INFO - 'PAGER': '/bin/cat', 09:24:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:24 INFO - 'PWD': '/builds/slave/test', 09:24:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:24 INFO - 'SHELL': '/bin/bash', 09:24:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:24 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:24 INFO - 'USER': 'cltbld', 09:24:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:24 INFO - 'XPC_FLAGS': '0x0', 09:24:24 INFO - 'XPC_SERVICE_NAME': '0', 09:24:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:27 INFO - warning: no files found matching '*.txt' 09:24:27 INFO - no previously-included directories found matching 'build' 09:24:27 INFO - no previously-included directories found matching 'dist' 09:24:27 INFO - no previously-included directories found matching '.git*' 09:24:27 INFO - Installing collected packages: jsonschema, functools32 09:24:27 INFO - Running setup.py install for functools32 09:24:27 INFO - warning: no files found matching '*.txt' 09:24:27 INFO - no previously-included directories found matching 'build' 09:24:27 INFO - no previously-included directories found matching 'dist' 09:24:27 INFO - no previously-included directories found matching '.git*' 09:24:28 INFO - Successfully installed jsonschema functools32 09:24:28 INFO - Cleaning up... 09:24:28 INFO - Return code: 0 09:24:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:24:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:28 INFO - 'HOME': '/Users/cltbld', 09:24:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:28 INFO - 'LOGNAME': 'cltbld', 09:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:28 INFO - 'MOZ_NO_REMOTE': '1', 09:24:28 INFO - 'NO_EM_RESTART': '1', 09:24:28 INFO - 'PAGER': '/bin/cat', 09:24:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:28 INFO - 'PWD': '/builds/slave/test', 09:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:28 INFO - 'SHELL': '/bin/bash', 09:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:28 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:28 INFO - 'USER': 'cltbld', 09:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:28 INFO - 'XPC_FLAGS': '0x0', 09:24:28 INFO - 'XPC_SERVICE_NAME': '0', 09:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:28 INFO - Cleaning up... 09:24:28 INFO - Return code: 0 09:24:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:24:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:28 INFO - 'HOME': '/Users/cltbld', 09:24:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:28 INFO - 'LOGNAME': 'cltbld', 09:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:28 INFO - 'MOZ_NO_REMOTE': '1', 09:24:28 INFO - 'NO_EM_RESTART': '1', 09:24:28 INFO - 'PAGER': '/bin/cat', 09:24:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:28 INFO - 'PWD': '/builds/slave/test', 09:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:28 INFO - 'SHELL': '/bin/bash', 09:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:28 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:28 INFO - 'USER': 'cltbld', 09:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:28 INFO - 'XPC_FLAGS': '0x0', 09:24:28 INFO - 'XPC_SERVICE_NAME': '0', 09:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:32 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 09:24:32 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 09:24:32 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 09:24:32 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 09:24:32 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 09:24:32 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 09:24:32 INFO - Downloading docopt-0.6.1.tar.gz 09:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:32 INFO - Running setup.py install for blobuploader 09:24:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:32 INFO - Running setup.py install for requests 09:24:33 INFO - Running setup.py install for docopt 09:24:33 INFO - Successfully installed blobuploader requests docopt 09:24:33 INFO - Cleaning up... 09:24:33 INFO - Return code: 0 09:24:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:33 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:33 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 09:24:33 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 09:24:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:33 INFO - 'HOME': '/Users/cltbld', 09:24:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:33 INFO - 'LOGNAME': 'cltbld', 09:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:33 INFO - 'MOZ_NO_REMOTE': '1', 09:24:33 INFO - 'NO_EM_RESTART': '1', 09:24:33 INFO - 'PAGER': '/bin/cat', 09:24:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:33 INFO - 'PWD': '/builds/slave/test', 09:24:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:33 INFO - 'SHELL': '/bin/bash', 09:24:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:33 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:33 INFO - 'USER': 'cltbld', 09:24:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:33 INFO - 'XPC_FLAGS': '0x0', 09:24:33 INFO - 'XPC_SERVICE_NAME': '0', 09:24:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:33 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-1MUTnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:33 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-u7Xz7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:33 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-hZTrT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-a_Uzxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-A31V95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-yx6YSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Tv8uzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-pni91_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-G4IuC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-cPUwE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sCZWZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-WsmnW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:34 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SKwjGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-LNLOLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-pPYjo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ecN6Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:35 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-H6vaUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:35 INFO - Running setup.py install for manifestparser 09:24:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:35 INFO - Running setup.py install for mozcrash 09:24:35 INFO - Running setup.py install for mozdebug 09:24:35 INFO - Running setup.py install for mozdevice 09:24:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Running setup.py install for mozfile 09:24:36 INFO - Running setup.py install for mozhttpd 09:24:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Running setup.py install for mozinfo 09:24:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Running setup.py install for mozInstall 09:24:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Running setup.py install for mozleak 09:24:36 INFO - Running setup.py install for mozlog 09:24:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Running setup.py install for moznetwork 09:24:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:37 INFO - Running setup.py install for mozprocess 09:24:37 INFO - Running setup.py install for mozprofile 09:24:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:37 INFO - Running setup.py install for mozrunner 09:24:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:37 INFO - Running setup.py install for mozscreenshot 09:24:37 INFO - Running setup.py install for moztest 09:24:37 INFO - Running setup.py install for mozversion 09:24:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:38 INFO - Cleaning up... 09:24:38 INFO - Return code: 0 09:24:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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')]} 09:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:38 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:38 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 09:24:38 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 09:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:38 INFO - 'HOME': '/Users/cltbld', 09:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:38 INFO - 'LOGNAME': 'cltbld', 09:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:38 INFO - 'MOZ_NO_REMOTE': '1', 09:24:38 INFO - 'NO_EM_RESTART': '1', 09:24:38 INFO - 'PAGER': '/bin/cat', 09:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:38 INFO - 'PWD': '/builds/slave/test', 09:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:38 INFO - 'SHELL': '/bin/bash', 09:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:38 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:38 INFO - 'USER': 'cltbld', 09:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:38 INFO - 'XPC_FLAGS': '0x0', 09:24:38 INFO - 'XPC_SERVICE_NAME': '0', 09:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-oo49Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:38 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)) 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-RGGkCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:38 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)) 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-BwAqqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:38 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)) 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-43JDjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:38 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)) 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-u4BVwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:38 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)) 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-x0Emx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:38 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)) 09:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-UCJj3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-jTPiy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Mvtsy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-YraCBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-T9w5KA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-0H8D6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Q06eLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bg4iVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-t9SyiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:39 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)) 09:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-6I30aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:40 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)) 09:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:40 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-5q08gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:40 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)) 09:24:40 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)) 09:24:40 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)) 09:24:40 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)) 09:24:40 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)) 09:24:40 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)) 09:24:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:42 INFO - Downloading blessings-1.6.tar.gz 09:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:42 INFO - Installing collected packages: blessings 09:24:42 INFO - Running setup.py install for blessings 09:24:42 INFO - Successfully installed blessings 09:24:42 INFO - Cleaning up... 09:24:43 INFO - Return code: 0 09:24:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:43 INFO - Reading from file tmpfile_stdout 09:24:43 INFO - Current package versions: 09:24:43 INFO - blessings == 1.6 09:24:43 INFO - blobuploader == 1.2.4 09:24:43 INFO - docopt == 0.6.1 09:24:43 INFO - functools32 == 3.2.3-2 09:24:43 INFO - jsonschema == 2.5.1 09:24:43 INFO - manifestparser == 1.1 09:24:43 INFO - mozInstall == 1.12 09:24:43 INFO - mozcrash == 1.0 09:24:43 INFO - mozdebug == 0.1 09:24:43 INFO - mozdevice == 0.49 09:24:43 INFO - mozfile == 1.2 09:24:43 INFO - mozhttpd == 0.7 09:24:43 INFO - mozinfo == 0.9 09:24:43 INFO - mozleak == 0.1 09:24:43 INFO - mozlog == 3.4 09:24:43 INFO - moznetwork == 0.27 09:24:43 INFO - mozprocess == 0.25 09:24:43 INFO - mozprofile == 0.28 09:24:43 INFO - mozrunner == 6.13 09:24:43 INFO - mozscreenshot == 0.1 09:24:43 INFO - mozsystemmonitor == 0.3 09:24:43 INFO - moztest == 0.8 09:24:43 INFO - mozversion == 1.4 09:24:43 INFO - psutil == 3.1.1 09:24:43 INFO - requests == 1.2.3 09:24:43 INFO - wsgiref == 0.1.2 09:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:43 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')]} 09:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:43 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')]} 09:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:43 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:43 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 09:24:43 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 09:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:43 INFO - 'HOME': '/Users/cltbld', 09:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:43 INFO - 'LOGNAME': 'cltbld', 09:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:43 INFO - 'MOZ_NO_REMOTE': '1', 09:24:43 INFO - 'NO_EM_RESTART': '1', 09:24:43 INFO - 'PAGER': '/bin/cat', 09:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:43 INFO - 'PWD': '/builds/slave/test', 09:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:43 INFO - 'SHELL': '/bin/bash', 09:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:43 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:43 INFO - 'USER': 'cltbld', 09:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:43 INFO - 'XPC_FLAGS': '0x0', 09:24:43 INFO - 'XPC_SERVICE_NAME': '0', 09:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 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)) 09:24:43 INFO - Cleaning up... 09:24:43 INFO - Return code: 0 09:24:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:43 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')]} 09:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:43 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')]} 09:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:43 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 0x10b172b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b175768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd94c3520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, '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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 09:24:43 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 09:24:43 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 09:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:43 INFO - 'HOME': '/Users/cltbld', 09:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:43 INFO - 'LOGNAME': 'cltbld', 09:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:43 INFO - 'MOZ_NO_REMOTE': '1', 09:24:43 INFO - 'NO_EM_RESTART': '1', 09:24:43 INFO - 'PAGER': '/bin/cat', 09:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:43 INFO - 'PWD': '/builds/slave/test', 09:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:43 INFO - 'SHELL': '/bin/bash', 09:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:43 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:43 INFO - 'USER': 'cltbld', 09:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:43 INFO - 'XPC_FLAGS': '0x0', 09:24:43 INFO - 'XPC_SERVICE_NAME': '0', 09:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:24:43 INFO - Cleaning up... 09:24:43 INFO - Return code: 0 09:24:43 INFO - Running post-action listener: _resource_record_post_action 09:24:43 INFO - Running post-action listener: _start_resource_monitoring 09:24:43 INFO - Starting resource monitoring. 09:24:43 INFO - [mozharness: 2017-04-21 16:24:43.927605Z] Finished create-virtualenv step (success) 09:24:43 INFO - [mozharness: 2017-04-21 16:24:43.928578Z] Running install step. 09:24:43 INFO - Running pre-action listener: _resource_record_pre_action 09:24:43 INFO - Running main action method: install 09:24:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:44 INFO - Reading from file tmpfile_stdout 09:24:44 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:44 INFO - Reading from file tmpfile_stdout 09:24:44 INFO - Output received: 09:24:44 INFO - Usage: mozinstall [options] installer 09:24:44 INFO - Options: 09:24:44 INFO - -h, --help show this help message and exit 09:24:44 INFO - -d DEST, --destination=DEST 09:24:44 INFO - Directory to install application into. [default: 09:24:44 INFO - "/builds/slave/test"] 09:24:44 INFO - --app=APP Application being installed. [default: firefox] 09:24:44 INFO - mkdir: /builds/slave/test/build/application 09:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:24:59 INFO - Reading from file tmpfile_stdout 09:24:59 INFO - Output received: 09:24:59 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:24:59 INFO - Running post-action listener: _resource_record_post_action 09:24:59 INFO - [mozharness: 2017-04-21 16:24:59.332175Z] Finished install step (success) 09:24:59 INFO - [mozharness: 2017-04-21 16:24:59.332357Z] Running run-tests step. 09:24:59 INFO - Running pre-action listener: _resource_record_pre_action 09:24:59 INFO - Running main action method: run_tests 09:24:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:24:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:24:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:24:59 INFO - 2017-04-21 09:24:59.346 screenresolution[1624:7290] starting screenresolution argv=screenresolution get 09:24:59 INFO - 2017-04-21 09:24:59.349 screenresolution[1624:7290] Display 0: 1600x1200x32@60 09:24:59 INFO - 2017-04-21 09:24:59.355 screenresolution[1625:7292] starting screenresolution argv=screenresolution list 09:24:59 INFO - Available Modes on Display 0 09:24:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:24:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:24:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:24:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:24:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:24:59 INFO - Intel Iris: 09:24:59 INFO - Chipset Model: Intel Iris 09:24:59 INFO - Type: GPU 09:24:59 INFO - Bus: Built-In 09:24:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:24:59 INFO - Vendor: Intel (0x8086) 09:24:59 INFO - Device ID: 0x0a2e 09:24:59 INFO - Revision ID: 0x0009 09:24:59 INFO - Displays: 09:24:59 INFO - Display: 09:24:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:24:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:24:59 INFO - Display Serial Number: 1600x1200 60 09:24:59 INFO - Main Display: Yes 09:24:59 INFO - Mirror: Off 09:24:59 INFO - Online: Yes 09:24:59 INFO - Rotation: Supported 09:24:59 INFO - Return code: 0 09:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:24:59 INFO - Python 2.7.3 09:24:59 INFO - Return code: 0 09:24:59 INFO - grabbing minidump binary from tooltool 09:24: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')]} 09:24:59 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 0x10af50d30>, '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 0x10ae77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af45be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:24:59 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 09:24:59 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 09:24:59 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 09:24:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:24:59 INFO - Return code: 0 09:24:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:24:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:24:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:24:59 INFO - ENV: RUST_BACKTRACE is now 1 09:24:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:24:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:24:59 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/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 09:24:59 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/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 09:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jVqEITsCVH/Render', 09:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:59 INFO - 'HOME': '/Users/cltbld', 09:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:59 INFO - 'LOGNAME': 'cltbld', 09:24:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:24:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:59 INFO - 'MOZ_NO_REMOTE': '1', 09:24:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:24:59 INFO - 'NO_EM_RESTART': '1', 09:24:59 INFO - 'PAGER': '/bin/cat', 09:24:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:59 INFO - 'PWD': '/builds/slave/test', 09:24:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:59 INFO - 'RUST_BACKTRACE': '1', 09:24:59 INFO - 'SHELL': '/bin/bash', 09:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r4uUMVhmPJ/Listeners', 09:24:59 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:59 INFO - 'USER': 'cltbld', 09:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:59 INFO - 'XPC_FLAGS': '0x0', 09:24:59 INFO - 'XPC_SERVICE_NAME': '0', 09:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:59 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/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 09:24:59 INFO - mozversion application_buildid: 20170421075927 09:24:59 INFO - mozversion application_changeset: 6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7 09:24:59 INFO - mozversion application_display_name: Firefox 09:24:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:24:59 INFO - mozversion application_name: Firefox 09:24:59 INFO - mozversion application_remotingname: firefox 09:24:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:24:59 INFO - mozversion application_vendor: Mozilla 09:24:59 INFO - mozversion application_version: 54.0 09:24:59 INFO - mozversion platform_buildid: 20170421075927 09:24:59 INFO - mozversion platform_changeset: 6ef4b8dc8d1a215b795b6612d2865efc8cb1b0f7 09:24:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:24:59 INFO - mozversion platform_version: 54.0 09:24:59 INFO - using testdate: 1492791899 09:24:59 INFO - actual date: 1492791899 09:24:59 INFO - starting webserver on 'localhost:49242' 09:24:59 INFO - SUITE-START | Running 2 tests 09:24:59 INFO - TEST-START | dromaeo_css 09:24:59 INFO - operating with platform_type : mac_ 09:24:59 INFO - Initialising browser for dromaeo_css test... 09:24:59 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpY0i74O/profile http://localhost:49242/getInfo.html) 09:25:02 INFO - PROCESS | 1635 | 2017-04-21 09:25:02.928 plugin-container[1638:7453] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 09:25:02 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:25:02 INFO - PROCESS | 1635 | 2017-04-21 09:25:02.928 plugin-container[1638:7453] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 09:25:02 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:25:02 INFO - PROCESS | 1635 | 2017-04-21 09:25:02.928 plugin-container[1638:7453] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:25:02 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 09:25:02 INFO - PROCESS | 1635 | colorDepth:24 09:25:02 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 09:25:02 INFO - PROCESS | 1635 | __metrics 09:25:03 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:25:03 INFO - TEST-INFO | 1635: exit 0 09:25:03 INFO - Browser initialized. 09:25:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:25:03 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpY0i74O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:06 INFO - PROCESS | 1639 | 2017-04-21 09:25:06.009 plugin-container[1640:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864f, name = 'com.apple.tsm.portname' 09:25:06 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:25:06 INFO - PROCESS | 1639 | 2017-04-21 09:25:06.009 plugin-container[1640:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:25:06 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:25:06 INFO - PROCESS | 1639 | 2017-04-21 09:25:06.009 plugin-container[1640:7590] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:25:15 INFO - PROCESS | 1639 | RSS: Main: 174456832 09:25:15 INFO - PROCESS | 1639 | 09:28:18 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:28:20 INFO - PROCESS | 1639 | RSS: Main: 193609728 09:28:20 INFO - PROCESS | 1639 | 09:31:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:31:23 INFO - PROCESS | 1639 | RSS: Main: 203034624 09:31:23 INFO - PROCESS | 1639 | 09:34:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:34:26 INFO - PROCESS | 1639 | RSS: Main: 204640256 09:34:26 INFO - PROCESS | 1639 | 09:37:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:37:28 INFO - PROCESS | 1639 | RSS: Main: 207728640 09:37:28 INFO - PROCESS | 1639 | 09:40:31 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:40:32 INFO - PROCESS | 1639 | RSS: Main: 210255872 09:40:32 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:43:35 INFO - PROCESS | 1639 | RSS: Main: 208605184 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | __start_tp_report 09:43:35 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 09:43:35 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 09:43:35 INFO - PROCESS | 1639 | |i|pagename|runs| 09:43:35 INFO - PROCESS | 1639 | |0;dojo.html;3647.489351490791;3692.1323488980083;3697.1126929536917;3700.5004324416195;3707.3512135376304;6225.03511955647;6229.781957631484;6248.4485617546825;6252.968735156323;6263.592866463677;6209.813705588839;6250.9062364064575;6255.343188965161;6269.780557680482;6271.843203919904;5568.693721845307;5626.409227031164;5654.547636189095;5655.462731040545;5746.540276777848;5692.5161361284345;5702.429757024306;5713.371529131792;5717.342505611846;5736.11090281005;5466.535344495723;5476.534494567966;5695.231143795576;5702.515286200678;5714.228579141804;5619.129034999582;5639.633423827479;5661.264035675377;5682.54539636828;5697.658140511582;4604.631629469634;5492.231087647732;5609.214709940612;5615.719214039323;5662.6036177467595;6031.758729650809;6204.100405441186;6272.6236425814595;6277.497800175975;6290.62256264621;5160.587153027749;6331.081993111019;6440.098386225932;6492.253390860064;6546.770863019819;6675.299093595151;6680.498962577826;6680.93319066808;6697.095892054559;6705.1283333166375;6328.841778955563;6331.651759153245;6365.140706004677;6380;6386.2975072741965;6305.653189074645;6495.4803615710625;6688;6690;6748.358905903931;6483.740650373932;6494.285628580852;6499.3175716549085;6516.152900122954;6523.902141467882;6151.876962460726;6446.968485042465;6551.737930482727;6604.570702904296;6660.966695166493;6267.467265282411;6268.749250030039;6415.101885736;6478.514111441661;6572.671366431659;5496.890062198733;6224.62652240867;6353.396427339396;6559.8032059038305;6604.405603495708;5242.056429842589;5263;5277.894442111136;5323.787048518016;5341.172118321666;3855.845766169378;4653.232216684277;4737.38414006177;4758.547937945889;4784.760761961888;6211.503079753699;6244.531660125509;6282.591631543935;6314.368563143741;6320.209973753281;6031.396860313934;6649.767258145942;6659.567128136656;6668.266490686021;6677.666116694243;5635.887282254332;6074.969625151934;6325.114483972247;6354.110424540568;6406.583572067801;5669.376368599534;5694.316681998187;5752.654840709571;5906.202662640574;5948.286205655262;6693;6719.798406047827;6728.7308507659145;6730.259671436138;6733.158355205599;6350.682465876595;6363.172787537776;6401.295857455583;6427.389398007182;6430.3891130342545;6654;6695.4978376620975;6716.261211266855;6737.427318677967;6749.5612785168805;5747.5401967841635;6428.646424446513;6447.548671593131;6491;6574.145361103026;6587.63767992765;6628.07206991012;6628.370304821035;6643.833904152435;6644.7009884554345;6230.501559875109;6417.93582064192;6431.163948686828;6442.710078046593;6551.312112228147;6260.123582698517;6299.149614801943;6313.147725057059;6329.367063293634;6344.873102538016;5383.977044361559;5809.738561764646;6297.338779428277;6304.842378940563;6311.526635502264;6187.102870083722;6275.748970041147;6405.839854003687;6411.96794016027;6412.9679351602945;6233.906491402542;6251.468625166912;6261.843453913689;6278.497720182283;6285.214348206474 09:43:35 INFO - PROCESS | 1639 | |1;ext.html;761.6648674583183;820.3519219816344;842.8904242448478;980.3137803537526;985.6254623243167;32446;41267.79366103169;42103;42143.946401339985;42300.78849605752;40140.59859401405;40366.99082522931;40574.59425405753;40622;40782.18435631286;8315.459495132827;8455;11418.371989540572;11704.122190835678;11786.587469438571;11449;11564.306141631529;11571.710707232302;11575.421228938561;11623.012043976272;11525.654230373102;11540.538378464851;11558.017568506644;11575.594854180106;11578.826317605242;11502.194846360759;11528.654140375802;11536.826947595793;11565.190436669436;11567.53729850805;11209.663710088711;11220.943895280552;11242.213045086848;11258.774824503545;11269.380184089872;34779.652203478145;34944.825275873714;35109.29781404383;35159.64840351615;35258.29483410317;3874.5350557932948;3893;3913.158670885773;3929.469521614574;3957.7348687333065;26083.73916260832;26322.736772632412;26401.867990660117;26925.730742692518;28196.577051344248;3122.625284965796;3196.6004249468815;3214.244652506659;3286.8685252589867;3381.712554432876;363518;363848;365309.3469065302;366506;366528.16735916416;45064;45370.54629453729;45531.31703024457;45554.77222613932;45617.08765824665;47166.5283347169;47229.05541889143;47431.051378972224;47467.05065898663;47636.761816190694;14342.354594043085;14427.855721442862;14512;14601.488947886774;14623.561293161221;12521.686957826127;12643;12645.620631381073;12657;12685.746285074247;9969.750756231007;10945.398003109745;12143.785621437786;12199.512019519298;12263.877361226274;5694.544436445074;5746.885062298814;5756.251687280626;5761.222234998221;5798.391168927286;1032.87605487342;1130.7448731907575;1146.633077415219;1176.9529218831321;1224.3449754381363;22913.08347666108;26671.86664066667;27363.589546156825;27849.72150278512;27982.160535183975;968.7917097824002;1288.5876519513854;1322.3288956308204;1324.9470021199045;1326.5754958413409;11117.674915904698;11875.88124118765;13516.797248041286;14178.716425671635;14975.850241497663;513434;514080;516264;517088;518464;40466.59533404687;49393.259101113515;50358;50563.241551376756;51534;1288.6005338345144;1499.6475828180369;1513.6896936128066;1516.2115699836024;1521.4218596933094;26492.94028238926;28659.14022579326;28794.136175913918;28888.98888538891;28982;40968.36126554904;41466.792666036476;41525.37711934263;41527;41876.37185442282;430305;432476;433662;434024;434047.6595234007;25662.486750265263;30533.389332212788;30789.692103078683;30931.84534077315;32626.347473050882;38781.70474045988;39192.41211381774;39653.60346396499;39866;40168.59831401648;28095.71904280931;28227.43545129127;28442;28621.998230061847;29195.85402072976;28568;28636.42727145487;28888.133355999355;28961.275968100123;29285.26786830261;31801.04596862098;32762.672373275964;33144.50283245705;33176.1705957353;33326.83356082525;28447.431051378444;28635.427291453638;28906.090549319597;28942.710572894;29444.55833162547;18045.639087218446;19674.60650787005;22914.885425572764;23432.88283558571;23938.760612393653 09:43:35 INFO - PROCESS | 1639 | |2;jquery.html;431.8553284649642;440.61886468204995;444.2913552883151;452.79397873967343;453.8683781703308;21076;21880.343589692315;22382;22627.208047718334;22760.77239227607;18984.62030759384;22091.779082209174;22425.215117470892;22945;23284.486508376944;6583.5062370322175;6854.246032936373;6871.347222013901;6888.965555172217;7415.332620064193;7565.773026809205;7671.6164191790185;7690.653920573588;7715.267049630276;7723.111842138142;7515.534470778201;7579.621018949058;7665.808354791147;7688.11586667532;7690.653920573588;6772.7808994381485;7179.676914538858;7712.730054448096;7766.534007959513;7810;7329.083864516935;7526;7536.4724469287175;7571.015767950186;7573.6213189340315;25815.612765808528;25859.482810343878;26179.607305890426;26292.73707262941;26943.865280673668;2938.853057347124;3032.5147976323683;3034.6510151332436;3070.385922815446;3074.8616312266004;13640.317984100855;15695.529134125994;16166.272517736612;16392.590185245346;16449.83550164495;1859.8698091133626;2045.7136000959877;2120.385088324384;2160.945976350604;2199.274239500961;138571;157534;171683;178135.9999999987;182084;14784.778228326584;17094.145292735564;17585;19485.415437536896;20817.479563010744;26161.29951553143;31602.841985789924;31957.360852782815;32020.87926922593;32022.519662205086;10554.261201715806;10583.62957296491;10628.89371106279;10788.622398216025;10932.289401188897;7456.515326503868;7465.4027677786735;9015.19935165335;9042.86435703465;9395.389299695498;7647.885281720779;9417.928055827708;9419.199368053654;9467.52662366879;9623.759406014906;3684.8526058957877;3974.642282194616;4201.722656328069;4242.936355954663;4257.4252475915355;1015.4922538730634;1015.7658444989225;1036.18659352408;1037.527924794217;1079.605943830512;20324.69512957307;20966.16135354569;20991.370258892257;21001.369958901258;21033.263835765676;1037.4034929915329;1041.422010784007;1065.3394895165047;1070.314998401024;1075.9085477734325;10126.54430550627;10214.08073273409;10865.587473628535;10892.257238841747;10932.836007459895;636312;732627;786878;793226;810896.8365474523;153165.23417382842;174130.12934935244;178443;180757;183685;1267.6070418170211;1334.1328136711022;1344.8722371374704;1345.8048582955416;1350.0886901341555;16812.159392030593;18981.715274271173;19261.422157335302;19615.901920490305;19709.507262318555;60379.69810150921;64914.35085649272;65027.67486162539;66479;66919.99240022807;94094;95079;95142.52428737812;97131;97484;11479.483423245807;11488.310701357945;11516.30902145874;11578.594749184074;11869.169158158846;10929.617463388744;12052.638420847388;12683.80974285405;12922.935385323013;13056.673583160496;10045.09594136502;10955.726106847074;11085.057770089632;11196;11483.712907177052;7901.881471778043;9724.562394692397;10212.642557510451;10315.48422578883;10476.895231047896;11014.237721964448;11363.090952723596;11386.430678465878;11604.245724027911;11647.30116193031;9553.426794392359;11398.08815294791;11523.596674116367;11599.882041875013;11687.123465740273;9454.95272523633;11091;11138.33170009802;11173.162012848907;11251.437428128724 09:43:35 INFO - PROCESS | 1639 | |3;mootools.html;1355.2478374502148;1506.771980835619;1580.6522565035702;1641.5321633334495;1659.7344424892017;10962;11457;11706.122040846949;11710.887465690766;11898.940505297483;11404;11552.191346605741;11622.767544649101;11642.708932276697;11642.708932276697;2345.0971376020225;2377.7146742390937;2383.9987205373786;2390.5816482115647;2393.8922748476275;2399.7120345558556;2446.71862735785;2513.45960618467;2515.1197081021587;2518.9496210075786;2443.0838435586656;2496.787773039294;2508.949821003579;2513.3339664988794;2515.5975043992967;2438.5366780311683;2471.2462698876834;2479.119912431088;2480.06997375984;2484.6769919910475;2459.827812053157;2470.8270421070533;2480.34270918205;2481.8138639602103;2488.14159115105;11229.831552526719;11374.943125284404;11391;11391.886081139248;11397.886021139766;5949.672767997802;6070.301915279755;6136.662483563402;6137.263528376623;6139.87720245597;3657.780533167992;3759.3233218020746;3788.583255841855;3814.4278358246206;3870.7290489665734;1264.2477725753163;1907.7138429235588;1919.1267973072218;1937.3509874192162;1941.5825597496605;13922.860771392257;14173;15524.379024838992;15652.843471565253;15951;4203.936940945889;4759.214729569651;4795.136875362398;4976.477469865685;4992.076465853828;11939.044876409866;12028.218165819193;12073.698157546132;12095.516179352904;12106.273623582612;6057;6069.393060693896;6082.239720034996;6090.482309003695;6096.756129754849;5347.51872331492;5369.4362091980565;5484.670919744828;5542.944570554324;5562.582806289463;4011.7191796574543;4058.9594104059174;4102.2000709861295;4138.979305103516;4166.104287578185;943.5659596585648;943.8206740719241;1083.6029048931657;1089.5587287148717;1091.3233795047122;924.7595625137378;942.0908822985851;943.2783920300975;966.6954909203579;1003.2325271167563;6749.358810912955;6778.593284402951;6792.694328755218;6971.6165610891885;7002.75490357835;904.3669431398048;1007.3250921882357;1023.8976102389851;1037.271974706517;1044.3107549017611;3995.860144894973;4228.5982831630945;4370.847020354272;4489.775511224426;4518.977405112953;12989.675258118434;13749.862501375059;14384.640383990274;14405.279736013157;14424.639384015274;13234.470621175238;13493.32533373347;13617.319134043259;13653.795193072112;13678.794818077737;8980.46117232968;10422.583096676048;10556.683299501028;10690.144788416908;10739.892601073889;6508.869822603616;6899.586024838526;7129.607871566906;7226.168990565942;7243.782686519413;10095.394276343442;12127.878721212675;12154.392280385768;12193.207441516273;12206.511739530319;10351.534180961724;12813.679658008625;13055.869441305465;13118.868811311764;13185.945124389835;9105.635774568944;9189;9281.767955801159;9285.07149285066;9309.301802364986;8950.731478055668;10437.530311136168;10440.791184176425;10445.373277603065;10556.788864222826;8846.932296213872;8986.05646407144;8997.55012249398;9170.770730731785;9177.357584968988;8644.481331120152;8681.435542701673;8728.51993140358;8759.343049271203;8871.689490867788;7977.48146370484;8079.030516338077;8150.918490815253;8155.877661834958;8177.632006559838;5183.144781111075;5801.970990145022;5928.199693041384;6017.368176341596;6055.788047418319;5966;5990.3710110439515;6092.8781424372155;6100.664463454553;6241.500679945502 09:43:35 INFO - PROCESS | 1639 | |4;prototype.html;363.14660547712873;364.6535790998549;380.5718566612561;400.8917592250094;405.73627142357475;4157.937630935538;4850.660453768238;4987.501249875011;5289.942011597686;5511.366192887819;4525.718853830979;4929.151568161939;5210.218467229917;5309.973450132744;5332.173513105465;4467.642588592913;4658.301254811771;4676.007355292615;4709.835155769549;4729.929051064237;4036.41669331628;4316.330968699841;4327.1345730853955;4430.778461076933;4618.838340658078;4259.913722000885;4266.871993840175;4319.632831209351;4585.564371384713;4615.238485649864;3763.8494460221696;4556.681032327739;4595.471520775121;4642.930356044662;4675.322078298662;3831.501904752364;4420.734755914656;4426.690131690783;4436;4451.755153466558;4161.729487583298;4860.816165411661;5214.949254686013;5218.373795144569;5271;4511.526289739563;4988.850334489971;4993.425756038066;5159.948400515985;5411.431799661018;4949.554540091373;5412.21271045412;5583.35791383992;5818.389069147764;5821.75862293016;4116.85923319875;4381.3208952612395;4426.933595996063;4523.575073851726;4858.951410485885;5244.160934250502;5453.536449401806;5686.124717266615;5826.251604941959;5827.766889324422;4240.575942405735;4930.8767280818265;4986.875328116754;5008.323876276729;5020.648554601216;5076.340075790197;5091.287219789232;5092.34498787888;5153.3558305211845;5163.173892177234;4275.593818587229;4501.572350626685;4894.95105048945;5223.843284701465;5295.576353891679;5424.511793938565;5682.431756824367;5688.431156884279;5693.946619875336;5712.914306285409;1465.3625672832352;1646.4895882276533;1734.3236137906226;1742.32920325673;1756.9297228110731;1320.966975825612;1368.3568722700315;1384.5254803634098;1391.7703578909568;1401.976557113313;5329.307190065267;5453.7818487260065;5459.863503412367;5476.698781566973;5488.588355873326;5333.493318134771;5545.861353466115;5635.239242702265;5935.792247271324;5948.7323070461935;5037.29477873098;5139.922901156486;5410.513053825096;5439.558516993;5492.615516913777;2736.7421876951594;2966.4067186562775;3064.6016017917973;3089.536569514546;3089.969100309013;5354.892902141935;5457.590680698963;6058.787942421994;6097.5731698780655;6187.628742275478;5079.4412614612365;5909.054551271776;5935.495482883917;5956.285245770535;6112.524427606826;5390.649607775482;5613.158026296007;5923.223033089969;6192.721327540181;6194.845128871814;4717.693349932243;5527.462424048532;5813.331466881363;5865.589408741518;5981.910271345846;5286.656367336111;5345.438728933406;5578.721063946868;5736.852629474205;5756.366799652119;5324.627276090791;5839.832033593383;6127.509799216141;6262.311145774052;6299.2598219774545;4557.521460246627;5042.420121686053;5152.6650767699975;5162.1932323687915;5227.163653815372;5611.242482264842;5938.138969849434;5953.523718102455;5974;6039.909401358896;5000.774965126505;5380.946190538045;5761.530809643514;5845.970770146122;5862.3844496328975;5507.889842203213;5613.550915926798;5725.1908396946765;5821.563382746226;5910.931174089034;5608.719564021864;5672.914906276326;5703.5722320826935;5707.7454405510425;5752.9712351437975;5330.733463326741;5516.738501800405;5576.275084239023;5807.303123625192;5910.704464776658;5206.453322401245;5354.7858085676135;5377.462253774747;5713.742881570256;5740.6268592541355 09:43:35 INFO - PROCESS | 1639 | |5;yui.html;753.9999999999997;754.3927138653382;783.7570353190509;823.7611092783089;886.8714036464713;16294;17202.225899834506;17432.825671743263;17441.7383739244;17465.301387944484;13276.535321263758;15855.92072039641;16038.198090095508;16063.598410039725;16237.756433653505;806.9677212911477;980.8430651095827;988.0514705882325;1021.3718563083725;1025.077430312717;1008.5864795433873;1020.367372229219;1020.8060468510996;1020.8723909511311;1021.9131373833233;1068.9251752377336;1075.3332933581191;1078.6817888722846;1078.7680648660537;1080.0171843622306;1063.6702622187108;1069.6095924987324;1073.7637719701654;1073.9463026848666;1074.919381046417;785.2187073861487;789.1831953927657;792.5007245435396;796.7370767646663;798.8601994650912;16428.6714265714;16899.23953422099;17172.484825455256;17271;17511.64976700459;14477.927610361987;14716.70566588673;14797.630059248497;14834.258287085602;14865.628359291104;12000.699807552906;12049.57826476078;12061.698457538543;12162.756744865143;12320.691982700415;3822.1591249924973;3915.23652887687;4054.553999060101;4061.086255592468;4062.1875624875142;115144;120989;123453.76546234512;123645.38177309146;124223.3788831059;11420.543178272776;11425.257358271685;11602.361870097056;11627.12796540263;11876.465559049864;22988.380176780018;23247.651285230735;23303.41741456477;23390.298291050956;23632;8247.752567422987;8258.793530161674;8274.958625206835;8328.08391076981;8339.416240863202;4528.773561321921;4577.313402989512;4594.632429405639;4640;4645.256758918556;3082.475979083561;3715.9256814864093;3726.329260733094;3746.1945681678026;3762.698984081267;676.8375589858399;685.1983179679787;694.5034300475185;697.5082566790401;700.8142842146835;514.9572116464116;567.9148127780866;578.528499273091;585.420433770564;610.4810910725936;10684.916496072588;10869;10931.96146366094;10973.780524389467;11158.442077896236;642.874639445313;690.6443181761397;703.2545501768142;732.4323649171828;733.8935854301096;4039.838406463767;4844.716150220194;5064.746762661852;5163.948360516422;5173.560247379015;101541;109204;115072;116330.41834790772;118259;71682;96722;98757;100340;102782;73773;78132;80499;82564;84473;11098.223124381217;11226.270292430965;11234.719132021764;11303.604373846876;11453.656390308304;48742;49207;50550;51432.74283628558;52008;71383;71598.64200678964;72597;75879.62060189663;79790;10613.257072005194;11560.843915608371;12347.931873479361;13341.443835602748;13903.513377031755;15413.691726165638;15603.921980390025;15710.214489275719;15842.287097080889;16398.26207820675;13989.510367137102;14152.78770818459;14457.493987710379;14550.199739014033;15000;13379.933100334436;13396.866031339952;13835.930820345833;14034;14405.42378304898;13587.388567514683;14045.438182472586;14276.000679952303;14310.928445358122;14316;10122.544485498023;10440.37357758537;10535.525901334611;10641.467926603793;11185.32888026721;10122.486940003155;11257.04315133223;11434.313941163557;11668.533258669559;11706.239094458833 09:43:35 INFO - PROCESS | 1639 | __end_tp_report 09:43:35 INFO - PROCESS | 1639 | __start_cc_report 09:43:35 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,390 09:43:35 INFO - PROCESS | 1639 | __end_cc_report 09:43:35 INFO - PROCESS | 1639 | __startTimestamp1492793015521__endTimestamp 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | ------- Summary: start ------- 09:43:35 INFO - PROCESS | 1639 | Number of tests: 6 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:6080.03 Median:6285.21 stddev:658.26 (10.5%) stddev-sans-first:632.01 09:43:35 INFO - PROCESS | 1639 | Values: 3647.5 3692.1 3697.1 3700.5 3707.4 6225.0 6229.8 6248.4 6253.0 6263.6 6209.8 6250.9 6255.3 6269.8 6271.8 5568.7 5626.4 5654.5 5655.5 5746.5 5692.5 5702.4 5713.4 5717.3 5736.1 5466.5 5476.5 5695.2 5702.5 5714.2 5619.1 5639.6 5661.3 5682.5 5697.7 4604.6 5492.2 5609.2 5615.7 5662.6 6031.8 6204.1 6272.6 6277.5 6290.6 5160.6 6331.1 6440.1 6492.3 6546.8 6675.3 6680.5 6680.9 6697.1 6705.1 6328.8 6331.7 6365.1 6380.0 6386.3 6305.7 6495.5 6688.0 6690.0 6748.4 6483.7 6494.3 6499.3 6516.2 6523.9 6151.9 6447.0 6551.7 6604.6 6661.0 6267.5 6268.7 6415.1 6478.5 6572.7 5496.9 6224.6 6353.4 6559.8 6604.4 5242.1 5263.0 5277.9 5323.8 5341.2 3855.8 4653.2 4737.4 4758.5 4784.8 6211.5 6244.5 6282.6 6314.4 6320.2 6031.4 6649.8 6659.6 6668.3 6677.7 5635.9 6075.0 6325.1 6354.1 6406.6 5669.4 5694.3 5752.7 5906.2 5948.3 6693.0 6719.8 6728.7 6730.3 6733.2 6350.7 6363.2 6401.3 6427.4 6430.4 6654.0 6695.5 6716.3 6737.4 6749.6 5747.5 6428.6 6447.5 6491.0 6574.1 6587.6 6628.1 6628.4 6643.8 6644.7 6230.5 6417.9 6431.2 6442.7 6551.3 6260.1 6299.1 6313.1 6329.4 6344.9 5384.0 5809.7 6297.3 6304.8 6311.5 6187.1 6275.7 6405.8 6412.0 6413.0 6233.9 6251.5 6261.8 6278.5 6285.2 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:56387.46 Median:29364.91 stddev:117634.23 (400.6%) stddev-sans-first:117890.09 09:43:35 INFO - PROCESS | 1639 | Values: 761.7 820.4 842.9 980.3 985.6 32446.0 41267.8 42103.0 42143.9 42300.8 40140.6 40367.0 40574.6 40622.0 40782.2 8315.5 8455.0 11418.4 11704.1 11786.6 11449.0 11564.3 11571.7 11575.4 11623.0 11525.7 11540.5 11558.0 11575.6 11578.8 11502.2 11528.7 11536.8 11565.2 11567.5 11209.7 11220.9 11242.2 11258.8 11269.4 34779.7 34944.8 35109.3 35159.6 35258.3 3874.5 3893.0 3913.2 3929.5 3957.7 26083.7 26322.7 26401.9 26925.7 28196.6 3122.6 3196.6 3214.2 3286.9 3381.7 363518.0 363848.0 365309.3 366506.0 366528.2 45064.0 45370.5 45531.3 45554.8 45617.1 47166.5 47229.1 47431.1 47467.1 47636.8 14342.4 14427.9 14512.0 14601.5 14623.6 12521.7 12643.0 12645.6 12657.0 12685.7 9969.8 10945.4 12143.8 12199.5 12263.9 5694.5 5746.9 5756.3 5761.2 5798.4 1032.9 1130.7 1146.6 1177.0 1224.3 22913.1 26671.9 27363.6 27849.7 27982.2 968.8 1288.6 1322.3 1324.9 1326.6 11117.7 11875.9 13516.8 14178.7 14975.9 513434.0 514080.0 516264.0 517088.0 518464.0 40466.6 49393.3 50358.0 50563.2 51534.0 1288.6 1499.6 1513.7 1516.2 1521.4 26492.9 28659.1 28794.1 28889.0 28982.0 40968.4 41466.8 41525.4 41527.0 41876.4 430305.0 432476.0 433662.0 434024.0 434047.7 25662.5 30533.4 30789.7 30931.8 32626.3 38781.7 39192.4 39653.6 39866.0 40168.6 28095.7 28227.4 28442.0 28622.0 29195.9 28568.0 28636.4 28888.1 28961.3 29285.3 31801.0 32762.7 33144.5 33176.2 33326.8 28447.4 28635.4 28906.1 28942.7 29444.6 18045.6 19674.6 22914.9 23432.9 23938.8 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:44268.73 Median:2140.67 stddev:126840.78 (5925.3%) stddev-sans-first:127153.90 09:43:35 INFO - PROCESS | 1639 | Values: 431.9 440.6 444.3 452.8 453.9 21076.0 21880.3 22382.0 22627.2 22760.8 18984.6 22091.8 22425.2 22945.0 23284.5 6583.5 6854.2 6871.3 6889.0 7415.3 7565.8 7671.6 7690.7 7715.3 7723.1 7515.5 7579.6 7665.8 7688.1 7690.7 6772.8 7179.7 7712.7 7766.5 7810.0 7329.1 7526.0 7536.5 7571.0 7573.6 25815.6 25859.5 26179.6 26292.7 26943.9 2938.9 3032.5 3034.7 3070.4 3074.9 13640.3 15695.5 16166.3 16392.6 16449.8 1859.9 2045.7 2120.4 2160.9 2199.3 138571.0 157534.0 171683.0 178136.0 182084.0 14784.8 17094.1 17585.0 19485.4 20817.5 26161.3 31602.8 31957.4 32020.9 32022.5 10554.3 10583.6 10628.9 10788.6 10932.3 7456.5 7465.4 9015.2 9042.9 9395.4 7647.9 9417.9 9419.2 9467.5 9623.8 3684.9 3974.6 4201.7 4242.9 4257.4 1015.5 1015.8 1036.2 1037.5 1079.6 20324.7 20966.2 20991.4 21001.4 21033.3 1037.4 1041.4 1065.3 1070.3 1075.9 10126.5 10214.1 10865.6 10892.3 10932.8 636312.0 732627.0 786878.0 793226.0 810896.8 153165.2 174130.1 178443.0 180757.0 183685.0 1267.6 1334.1 1344.9 1345.8 1350.1 16812.2 18981.7 19261.4 19615.9 19709.5 60379.7 64914.4 65027.7 66479.0 66920.0 94094.0 95079.0 95142.5 97131.0 97484.0 11479.5 11488.3 11516.3 11578.6 11869.2 10929.6 12052.6 12683.8 12922.9 13056.7 10045.1 10955.7 11085.1 11196.0 11483.7 7901.9 9724.6 10212.6 10315.5 10476.9 11014.2 11363.1 11386.4 11604.2 11647.3 9553.4 11398.1 11523.6 11599.9 11687.1 9455.0 11091.0 11138.3 11173.2 11251.4 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6884.79 Median:2511.14 stddev:4223.84 (168.2%) stddev-sans-first:4215.25 09:43:35 INFO - PROCESS | 1639 | Values: 1355.2 1506.8 1580.7 1641.5 1659.7 10962.0 11457.0 11706.1 11710.9 11898.9 11404.0 11552.2 11622.8 11642.7 11642.7 2345.1 2377.7 2384.0 2390.6 2393.9 2399.7 2446.7 2513.5 2515.1 2518.9 2443.1 2496.8 2508.9 2513.3 2515.6 2438.5 2471.2 2479.1 2480.1 2484.7 2459.8 2470.8 2480.3 2481.8 2488.1 11229.8 11374.9 11391.0 11391.9 11397.9 5949.7 6070.3 6136.7 6137.3 6139.9 3657.8 3759.3 3788.6 3814.4 3870.7 1264.2 1907.7 1919.1 1937.4 1941.6 13922.9 14173.0 15524.4 15652.8 15951.0 4203.9 4759.2 4795.1 4976.5 4992.1 11939.0 12028.2 12073.7 12095.5 12106.3 6057.0 6069.4 6082.2 6090.5 6096.8 5347.5 5369.4 5484.7 5542.9 5562.6 4011.7 4059.0 4102.2 4139.0 4166.1 943.6 943.8 1083.6 1089.6 1091.3 924.8 942.1 943.3 966.7 1003.2 6749.4 6778.6 6792.7 6971.6 7002.8 904.4 1007.3 1023.9 1037.3 1044.3 3995.9 4228.6 4370.8 4489.8 4519.0 12989.7 13749.9 14384.6 14405.3 14424.6 13234.5 13493.3 13617.3 13653.8 13678.8 8980.5 10422.6 10556.7 10690.1 10739.9 6508.9 6899.6 7129.6 7226.2 7243.8 10095.4 12127.9 12154.4 12193.2 12206.5 10351.5 12813.7 13055.9 13118.9 13185.9 9105.6 9189.0 9281.8 9285.1 9309.3 8950.7 10437.5 10440.8 10445.4 10556.8 8846.9 8986.1 8997.6 9170.8 9177.4 8644.5 8681.4 8728.5 8759.3 8871.7 7977.5 8079.0 8150.9 8155.9 8177.6 5183.1 5802.0 5928.2 6017.4 6055.8 5966.0 5990.4 6092.9 6100.7 6241.5 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4850.03 Median:5257.58 stddev:1310.51 (24.9%) stddev-sans-first:1270.17 09:43:35 INFO - PROCESS | 1639 | Values: 363.1 364.7 380.6 400.9 405.7 4157.9 4850.7 4987.5 5289.9 5511.4 4525.7 4929.2 5210.2 5310.0 5332.2 4467.6 4658.3 4676.0 4709.8 4729.9 4036.4 4316.3 4327.1 4430.8 4618.8 4259.9 4266.9 4319.6 4585.6 4615.2 3763.8 4556.7 4595.5 4642.9 4675.3 3831.5 4420.7 4426.7 4436.0 4451.8 4161.7 4860.8 5214.9 5218.4 5271.0 4511.5 4988.9 4993.4 5159.9 5411.4 4949.6 5412.2 5583.4 5818.4 5821.8 4116.9 4381.3 4426.9 4523.6 4859.0 5244.2 5453.5 5686.1 5826.3 5827.8 4240.6 4930.9 4986.9 5008.3 5020.6 5076.3 5091.3 5092.3 5153.4 5163.2 4275.6 4501.6 4895.0 5223.8 5295.6 5424.5 5682.4 5688.4 5693.9 5712.9 1465.4 1646.5 1734.3 1742.3 1756.9 1321.0 1368.4 1384.5 1391.8 1402.0 5329.3 5453.8 5459.9 5476.7 5488.6 5333.5 5545.9 5635.2 5935.8 5948.7 5037.3 5139.9 5410.5 5439.6 5492.6 2736.7 2966.4 3064.6 3089.5 3090.0 5354.9 5457.6 6058.8 6097.6 6187.6 5079.4 5909.1 5935.5 5956.3 6112.5 5390.6 5613.2 5923.2 6192.7 6194.8 4717.7 5527.5 5813.3 5865.6 5981.9 5286.7 5345.4 5578.7 5736.9 5756.4 5324.6 5839.8 6127.5 6262.3 6299.3 4557.5 5042.4 5152.7 5162.2 5227.2 5611.2 5938.1 5953.5 5974.0 6039.9 5000.8 5380.9 5761.5 5846.0 5862.4 5507.9 5613.6 5725.2 5821.6 5910.9 5608.7 5672.9 5703.6 5707.7 5753.0 5330.7 5516.7 5576.3 5807.3 5910.7 5206.5 5354.8 5377.5 5713.7 5740.6 09:43:35 INFO - PROCESS | 1639 | 09:43:35 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:21976.62 Median:16050.90 stddev:32115.63 (200.1%) stddev-sans-first:32166.19 09:43:35 INFO - PROCESS | 1639 | Values: 754.0 754.4 783.8 823.8 886.9 16294.0 17202.2 17432.8 17441.7 17465.3 13276.5 15855.9 16038.2 16063.6 16237.8 807.0 980.8 988.1 1021.4 1025.1 1008.6 1020.4 1020.8 1020.9 1021.9 1068.9 1075.3 1078.7 1078.8 1080.0 1063.7 1069.6 1073.8 1073.9 1074.9 785.2 789.2 792.5 796.7 798.9 16428.7 16899.2 17172.5 17271.0 17511.6 14477.9 14716.7 14797.6 14834.3 14865.6 12000.7 12049.6 12061.7 12162.8 12320.7 3822.2 3915.2 4054.6 4061.1 4062.2 115144.0 120989.0 123453.8 123645.4 124223.4 11420.5 11425.3 11602.4 11627.1 11876.5 22988.4 23247.7 23303.4 23390.3 23632.0 8247.8 8258.8 8275.0 8328.1 8339.4 4528.8 4577.3 4594.6 4640.0 4645.3 3082.5 3715.9 3726.3 3746.2 3762.7 676.8 685.2 694.5 697.5 700.8 515.0 567.9 578.5 585.4 610.5 10684.9 10869.0 10932.0 10973.8 11158.4 642.9 690.6 703.3 732.4 733.9 4039.8 4844.7 5064.7 5163.9 5173.6 101541.0 109204.0 115072.0 116330.4 118259.0 71682.0 96722.0 98757.0 100340.0 102782.0 73773.0 78132.0 80499.0 82564.0 84473.0 11098.2 11226.3 11234.7 11303.6 11453.7 48742.0 49207.0 50550.0 51432.7 52008.0 71383.0 71598.6 72597.0 75879.6 79790.0 10613.3 11560.8 12347.9 13341.4 13903.5 15413.7 15603.9 15710.2 15842.3 16398.3 13989.5 14152.8 14457.5 14550.2 15000.0 13379.9 13396.9 13835.9 14034.0 14405.4 13587.4 14045.4 14276.0 14310.9 14316.0 10122.5 10440.4 10535.5 10641.5 11185.3 10122.5 11257.0 11434.3 11668.5 11706.2 09:43:35 INFO - PROCESS | 1639 | -------- Summary: end -------- 09:43:35 INFO - PROCESS | 1639 | 09:43:36 INFO - TEST-INFO | 1639: exit 0 09:43:36 INFO - TEST-OK | dromaeo_css | took 1116116ms 09:43:36 INFO - TEST-START | kraken 09:43:36 INFO - operating with platform_type : mac_ 09:43:36 INFO - Initialising browser for kraken test... 09:43:36 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpV18JlB/profile http://localhost:49242/getInfo.html) 09:43:38 INFO - PROCESS | 1652 | 2017-04-21 09:43:38.646 plugin-container[1654:10004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 09:43:38 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:38 INFO - PROCESS | 1652 | 2017-04-21 09:43:38.647 plugin-container[1654:10004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 09:43:38 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:38 INFO - PROCESS | 1652 | 2017-04-21 09:43:38.647 plugin-container[1654:10004] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:43:38 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:43:38 INFO - PROCESS | 1652 | colorDepth:24 09:43:38 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:43:38 INFO - PROCESS | 1652 | __metrics 09:43:38 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:43:39 INFO - TEST-INFO | 1652: exit 0 09:43:39 INFO - Browser initialized. 09:43:39 INFO - Running cycle 1/1 for kraken test... 09:43:39 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpV18JlB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:41 INFO - PROCESS | 1655 | 2017-04-21 09:43:41.700 plugin-container[1656:10127] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 09:43:41 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:41 INFO - PROCESS | 1655 | 2017-04-21 09:43:41.701 plugin-container[1656:10127] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 09:43:41 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:41 INFO - PROCESS | 1655 | 2017-04-21 09:43:41.701 plugin-container[1656:10127] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:43:51 INFO - PROCESS | 1655 | RSS: Main: 174604288 09:43:51 INFO - PROCESS | 1655 | 09:43:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:55 INFO - PROCESS | 1655 | RSS: Main: 188207104 09:43:55 INFO - PROCESS | 1655 | 09:43:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:43:58 INFO - PROCESS | 1655 | RSS: Main: 196136960 09:43:58 INFO - PROCESS | 1655 | 09:44:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:44:02 INFO - PROCESS | 1655 | RSS: Main: 199766016 09:44:02 INFO - PROCESS | 1655 | 09:44:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:44:05 INFO - PROCESS | 1655 | RSS: Main: 200097792 09:44:05 INFO - PROCESS | 1655 | 09:44:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:08 INFO - PROCESS | 1655 | RSS: Main: 200105984 09:44:08 INFO - PROCESS | 1655 | 09:44:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:11 INFO - PROCESS | 1655 | RSS: Main: 204349440 09:44:11 INFO - PROCESS | 1655 | 09:44:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:15 INFO - PROCESS | 1655 | RSS: Main: 203264000 09:44:15 INFO - PROCESS | 1655 | 09:44:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:44:19 INFO - PROCESS | 1655 | RSS: Main: 203448320 09:44:19 INFO - PROCESS | 1655 | 09:44:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:21 INFO - PROCESS | 1655 | RSS: Main: 204398592 09:44:21 INFO - PROCESS | 1655 | 09:44:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:24 INFO - PROCESS | 1655 | RSS: Main: 204406784 09:44:24 INFO - PROCESS | 1655 | 09:44:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:26 INFO - PROCESS | 1655 | RSS: Main: 204464128 09:44:26 INFO - PROCESS | 1655 | 09:44:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:30 INFO - PROCESS | 1655 | RSS: Main: 204865536 09:44:30 INFO - PROCESS | 1655 | 09:44:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:34 INFO - PROCESS | 1655 | RSS: Main: 204607488 09:44:34 INFO - PROCESS | 1655 | 09:44:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:44:36 INFO - PROCESS | 1655 | RSS: Main: 206761984 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | __start_tp_report 09:44:36 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:44:36 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:44:36 INFO - PROCESS | 1655 | |i|pagename|runs| 09:44:36 INFO - PROCESS | 1655 | |0;ai-astar;108;117;118;119;119;119;117;136;174;164 09:44:36 INFO - PROCESS | 1655 | |1;audio-beat-detection;149;100;97;153;127;139;138;110;127;112 09:44:36 INFO - PROCESS | 1655 | |2;audio-dft;202;198;194;206;231;276;273;198;196;200 09:44:36 INFO - PROCESS | 1655 | |3;audio-fft;80;64;83;111;53;63;85;86;98;85 09:44:36 INFO - PROCESS | 1655 | |4;audio-oscillator;86;68;83;90;56;73;110;101;100;112 09:44:36 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;91;92;96;129;92;91;91;93;91;92 09:44:36 INFO - PROCESS | 1655 | |6;imaging-darkroom;98;129;125;153;139;97;138;149;97;98 09:44:36 INFO - PROCESS | 1655 | |7;imaging-desaturate;142;104;140;150;91;89;88;92;92;91 09:44:36 INFO - PROCESS | 1655 | |8;json-parse-financial;67;66;68;67;69;68;68;67;69;69 09:44:36 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;59;63;59;67;70;63;62;58;58;57 09:44:36 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;84;82;86;83;84;84;85;86;86;89 09:44:36 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;142;143;143;137;139;136;140;145;176;149 09:44:36 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;167;167;166;215;173;148;222;221;177;170 09:44:36 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;68;65;66;66;65;64;65;63;65 09:44:36 INFO - PROCESS | 1655 | __end_tp_report 09:44:36 INFO - PROCESS | 1655 | __start_cc_report 09:44:36 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,999 09:44:36 INFO - PROCESS | 1655 | __end_cc_report 09:44:36 INFO - PROCESS | 1655 | __startTimestamp1492793076250__endTimestamp 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:44:36 INFO - PROCESS | 1655 | Number of tests: 14 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:129.10 Median:119.00 stddev:22.23 (18.7%) stddev-sans-first:22.23 09:44:36 INFO - PROCESS | 1655 | Values: 108.0 117.0 118.0 119.0 119.0 119.0 117.0 136.0 174.0 164.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:138.50 stddev:19.82 (14.3%) stddev-sans-first:19.06 09:44:36 INFO - PROCESS | 1655 | Values: 149.0 100.0 97.0 153.0 127.0 139.0 138.0 110.0 127.0 112.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:217.40 Median:204.00 stddev:31.86 (15.6%) stddev-sans-first:33.31 09:44:36 INFO - PROCESS | 1655 | Values: 202.0 198.0 194.0 206.0 231.0 276.0 273.0 198.0 196.0 200.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:80.80 Median:84.00 stddev:17.15 (20.4%) stddev-sans-first:18.19 09:44:36 INFO - PROCESS | 1655 | Values: 80.0 64.0 83.0 111.0 53.0 63.0 85.0 86.0 98.0 85.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:70.50 stddev:18.42 (26.1%) stddev-sans-first:19.53 09:44:36 INFO - PROCESS | 1655 | Values: 86.0 68.0 83.0 90.0 56.0 73.0 110.0 101.0 100.0 112.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:92.00 stddev:11.76 (12.8%) stddev-sans-first:12.35 09:44:36 INFO - PROCESS | 1655 | Values: 91.0 92.0 96.0 129.0 92.0 91.0 91.0 93.0 91.0 92.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:122.30 Median:125.00 stddev:22.84 (18.3%) stddev-sans-first:22.47 09:44:36 INFO - PROCESS | 1655 | Values: 98.0 129.0 125.0 153.0 139.0 97.0 138.0 149.0 97.0 98.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:90.00 stddev:25.41 (28.2%) stddev-sans-first:23.77 09:44:36 INFO - PROCESS | 1655 | Values: 142.0 104.0 140.0 150.0 91.0 89.0 88.0 92.0 92.0 91.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 09:44:36 INFO - PROCESS | 1655 | Values: 67.0 66.0 68.0 67.0 69.0 68.0 68.0 67.0 69.0 69.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:62.50 stddev:4.27 (6.8%) stddev-sans-first:4.43 09:44:36 INFO - PROCESS | 1655 | Values: 59.0 63.0 59.0 67.0 70.0 63.0 62.0 58.0 58.0 57.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.50 stddev:1.97 (2.3%) stddev-sans-first:2.06 09:44:36 INFO - PROCESS | 1655 | Values: 84.0 82.0 86.0 83.0 84.0 84.0 85.0 86.0 86.0 89.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:143.00 stddev:11.55 (8.1%) stddev-sans-first:12.20 09:44:36 INFO - PROCESS | 1655 | Values: 142.0 143.0 143.0 137.0 139.0 136.0 140.0 145.0 176.0 149.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.60 Median:175.00 stddev:26.50 (15.1%) stddev-sans-first:27.50 09:44:36 INFO - PROCESS | 1655 | Values: 167.0 167.0 166.0 215.0 173.0 148.0 222.0 221.0 177.0 170.0 09:44:36 INFO - PROCESS | 1655 | 09:44:36 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.43 (2.2%) stddev-sans-first:1.39 09:44:36 INFO - PROCESS | 1655 | Values: 67.0 68.0 65.0 66.0 66.0 65.0 64.0 65.0 63.0 65.0 09:44:36 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:44:36 INFO - PROCESS | 1655 | 09:44:37 INFO - TEST-INFO | 1655: exit 0 09:44:37 INFO - TEST-OK | kraken | took 60995ms 09:44:37 INFO - SUITE-END | took 1177s 09:44:37 INFO - Completed test suite (00:19:37) 09:44:37 INFO - javascript benchmark 09:44:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7954.828091400573, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3647.489351490791, 3692.1323488980083, 3697.1126929536917, 3700.5004324416195, 3707.3512135376304, 6225.03511955647, 6229.781957631484, 6248.4485617546825, 6252.968735156323, 6263.592866463677, 6209.813705588839, 6250.9062364064575, 6255.343188965161, 6269.780557680482, 6271.843203919904, 5568.693721845307, 5626.409227031164, 5654.547636189095, 5655.462731040545, 5746.540276777848, 5692.5161361284345, 5702.429757024306, 5713.371529131792, 5717.342505611846, 5736.11090281005, 5466.535344495723, 5476.534494567966, 5695.231143795576, 5702.515286200678, 5714.228579141804, 5619.129034999582, 5639.633423827479, 5661.264035675377, 5682.54539636828, 5697.658140511582, 4604.631629469634, 5492.231087647732, 5609.214709940612, 5615.719214039323, 5662.6036177467595, 6031.758729650809, 6204.100405441186, 6272.6236425814595, 6277.497800175975, 6290.62256264621, 5160.587153027749, 6331.081993111019, 6440.098386225932, 6492.253390860064, 6546.770863019819, 6675.299093595151, 6680.498962577826, 6680.93319066808, 6697.095892054559, 6705.1283333166375, 6328.841778955563, 6331.651759153245, 6365.140706004677, 6380.0, 6386.2975072741965, 6305.653189074645, 6495.4803615710625, 6688.0, 6690.0, 6748.358905903931, 6483.740650373932, 6494.285628580852, 6499.3175716549085, 6516.152900122954, 6523.902141467882, 6151.876962460726, 6446.968485042465, 6551.737930482727, 6604.570702904296, 6660.966695166493, 6267.467265282411, 6268.749250030039, 6415.101885736, 6478.514111441661, 6572.671366431659, 5496.890062198733, 6224.62652240867, 6353.396427339396, 6559.8032059038305, 6604.405603495708, 5242.056429842589, 5263.0, 5277.894442111136, 5323.787048518016, 5341.172118321666, 3855.845766169378, 4653.232216684277, 4737.38414006177, 4758.547937945889, 4784.760761961888, 6211.503079753699, 6244.531660125509, 6282.591631543935, 6314.368563143741, 6320.209973753281, 6031.396860313934, 6649.767258145942, 6659.567128136656, 6668.266490686021, 6677.666116694243, 5635.887282254332, 6074.969625151934, 6325.114483972247, 6354.110424540568, 6406.583572067801, 5669.376368599534, 5694.316681998187, 5752.654840709571, 5906.202662640574, 5948.286205655262, 6693.0, 6719.798406047827, 6728.7308507659145, 6730.259671436138, 6733.158355205599, 6350.682465876595, 6363.172787537776, 6401.295857455583, 6427.389398007182, 6430.3891130342545, 6654.0, 6695.4978376620975, 6716.261211266855, 6737.427318677967, 6749.5612785168805, 5747.5401967841635, 6428.646424446513, 6447.548671593131, 6491.0, 6574.145361103026, 6587.63767992765, 6628.07206991012, 6628.370304821035, 6643.833904152435, 6644.7009884554345, 6230.501559875109, 6417.93582064192, 6431.163948686828, 6442.710078046593, 6551.312112228147, 6260.123582698517, 6299.149614801943, 6313.147725057059, 6329.367063293634, 6344.873102538016, 5383.977044361559, 5809.738561764646, 6297.338779428277, 6304.842378940563, 6311.526635502264, 6187.102870083722, 6275.748970041147, 6405.839854003687, 6411.96794016027, 6412.9679351602945, 6233.906491402542, 6251.468625166912, 6261.843453913689, 6278.497720182283, 6285.214348206474], "value": 6041.309567345464, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.6648674583183, 820.3519219816344, 842.8904242448478, 980.3137803537526, 985.6254623243167, 32446.0, 41267.79366103169, 42103.0, 42143.946401339985, 42300.78849605752, 40140.59859401405, 40366.99082522931, 40574.59425405753, 40622.0, 40782.18435631286, 8315.459495132827, 8455.0, 11418.371989540572, 11704.122190835678, 11786.587469438571, 11449.0, 11564.306141631529, 11571.710707232302, 11575.421228938561, 11623.012043976272, 11525.654230373102, 11540.538378464851, 11558.017568506644, 11575.594854180106, 11578.826317605242, 11502.194846360759, 11528.654140375802, 11536.826947595793, 11565.190436669436, 11567.53729850805, 11209.663710088711, 11220.943895280552, 11242.213045086848, 11258.774824503545, 11269.380184089872, 34779.652203478145, 34944.825275873714, 35109.29781404383, 35159.64840351615, 35258.29483410317, 3874.5350557932948, 3893.0, 3913.158670885773, 3929.469521614574, 3957.7348687333065, 26083.73916260832, 26322.736772632412, 26401.867990660117, 26925.730742692518, 28196.577051344248, 3122.625284965796, 3196.6004249468815, 3214.244652506659, 3286.8685252589867, 3381.712554432876, 363518.0, 363848.0, 365309.3469065302, 366506.0, 366528.16735916416, 45064.0, 45370.54629453729, 45531.31703024457, 45554.77222613932, 45617.08765824665, 47166.5283347169, 47229.05541889143, 47431.051378972224, 47467.05065898663, 47636.761816190694, 14342.354594043085, 14427.855721442862, 14512.0, 14601.488947886774, 14623.561293161221, 12521.686957826127, 12643.0, 12645.620631381073, 12657.0, 12685.746285074247, 9969.750756231007, 10945.398003109745, 12143.785621437786, 12199.512019519298, 12263.877361226274, 5694.544436445074, 5746.885062298814, 5756.251687280626, 5761.222234998221, 5798.391168927286, 1032.87605487342, 1130.7448731907575, 1146.633077415219, 1176.9529218831321, 1224.3449754381363, 22913.08347666108, 26671.86664066667, 27363.589546156825, 27849.72150278512, 27982.160535183975, 968.7917097824002, 1288.5876519513854, 1322.3288956308204, 1324.9470021199045, 1326.5754958413409, 11117.674915904698, 11875.88124118765, 13516.797248041286, 14178.716425671635, 14975.850241497663, 513434.0, 514080.0, 516264.0, 517088.0, 518464.0, 40466.59533404687, 49393.259101113515, 50358.0, 50563.241551376756, 51534.0, 1288.6005338345144, 1499.6475828180369, 1513.6896936128066, 1516.2115699836024, 1521.4218596933094, 26492.94028238926, 28659.14022579326, 28794.136175913918, 28888.98888538891, 28982.0, 40968.36126554904, 41466.792666036476, 41525.37711934263, 41527.0, 41876.37185442282, 430305.0, 432476.0, 433662.0, 434024.0, 434047.6595234007, 25662.486750265263, 30533.389332212788, 30789.692103078683, 30931.84534077315, 32626.347473050882, 38781.70474045988, 39192.41211381774, 39653.60346396499, 39866.0, 40168.59831401648, 28095.71904280931, 28227.43545129127, 28442.0, 28621.998230061847, 29195.85402072976, 28568.0, 28636.42727145487, 28888.133355999355, 28961.275968100123, 29285.26786830261, 31801.04596862098, 32762.672373275964, 33144.50283245705, 33176.1705957353, 33326.83356082525, 28447.431051378444, 28635.427291453638, 28906.090549319597, 28942.710572894, 29444.55833162547, 18045.639087218446, 19674.60650787005, 22914.885425572764, 23432.88283558571, 23938.760612393653], "value": 18636.255604958373, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.8553284649642, 440.61886468204995, 444.2913552883151, 452.79397873967343, 453.8683781703308, 21076.0, 21880.343589692315, 22382.0, 22627.208047718334, 22760.77239227607, 18984.62030759384, 22091.779082209174, 22425.215117470892, 22945.0, 23284.486508376944, 6583.5062370322175, 6854.246032936373, 6871.347222013901, 6888.965555172217, 7415.332620064193, 7565.773026809205, 7671.6164191790185, 7690.653920573588, 7715.267049630276, 7723.111842138142, 7515.534470778201, 7579.621018949058, 7665.808354791147, 7688.11586667532, 7690.653920573588, 6772.7808994381485, 7179.676914538858, 7712.730054448096, 7766.534007959513, 7810.0, 7329.083864516935, 7526.0, 7536.4724469287175, 7571.015767950186, 7573.6213189340315, 25815.612765808528, 25859.482810343878, 26179.607305890426, 26292.73707262941, 26943.865280673668, 2938.853057347124, 3032.5147976323683, 3034.6510151332436, 3070.385922815446, 3074.8616312266004, 13640.317984100855, 15695.529134125994, 16166.272517736612, 16392.590185245346, 16449.83550164495, 1859.8698091133626, 2045.7136000959877, 2120.385088324384, 2160.945976350604, 2199.274239500961, 138571.0, 157534.0, 171683.0, 178135.9999999987, 182084.0, 14784.778228326584, 17094.145292735564, 17585.0, 19485.415437536896, 20817.479563010744, 26161.29951553143, 31602.841985789924, 31957.360852782815, 32020.87926922593, 32022.519662205086, 10554.261201715806, 10583.62957296491, 10628.89371106279, 10788.622398216025, 10932.289401188897, 7456.515326503868, 7465.4027677786735, 9015.19935165335, 9042.86435703465, 9395.389299695498, 7647.885281720779, 9417.928055827708, 9419.199368053654, 9467.52662366879, 9623.759406014906, 3684.8526058957877, 3974.642282194616, 4201.722656328069, 4242.936355954663, 4257.4252475915355, 1015.4922538730634, 1015.7658444989225, 1036.18659352408, 1037.527924794217, 1079.605943830512, 20324.69512957307, 20966.16135354569, 20991.370258892257, 21001.369958901258, 21033.263835765676, 1037.4034929915329, 1041.422010784007, 1065.3394895165047, 1070.314998401024, 1075.9085477734325, 10126.54430550627, 10214.08073273409, 10865.587473628535, 10892.257238841747, 10932.836007459895, 636312.0, 732627.0, 786878.0, 793226.0, 810896.8365474523, 153165.23417382842, 174130.12934935244, 178443.0, 180757.0, 183685.0, 1267.6070418170211, 1334.1328136711022, 1344.8722371374704, 1345.8048582955416, 1350.0886901341555, 16812.159392030593, 18981.715274271173, 19261.422157335302, 19615.901920490305, 19709.507262318555, 60379.69810150921, 64914.35085649272, 65027.67486162539, 66479.0, 66919.99240022807, 94094.0, 95079.0, 95142.52428737812, 97131.0, 97484.0, 11479.483423245807, 11488.310701357945, 11516.30902145874, 11578.594749184074, 11869.169158158846, 10929.617463388744, 12052.638420847388, 12683.80974285405, 12922.935385323013, 13056.673583160496, 10045.09594136502, 10955.726106847074, 11085.057770089632, 11196.0, 11483.712907177052, 7901.881471778043, 9724.562394692397, 10212.642557510451, 10315.48422578883, 10476.895231047896, 11014.237721964448, 11363.090952723596, 11386.430678465878, 11604.245724027911, 11647.30116193031, 9553.426794392359, 11398.08815294791, 11523.596674116367, 11599.882041875013, 11687.123465740273, 9454.95272523633, 11091.0, 11138.33170009802, 11173.162012848907, 11251.437428128724], "value": 11815.085621350461, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.2478374502148, 1506.771980835619, 1580.6522565035702, 1641.5321633334495, 1659.7344424892017, 10962.0, 11457.0, 11706.122040846949, 11710.887465690766, 11898.940505297483, 11404.0, 11552.191346605741, 11622.767544649101, 11642.708932276697, 11642.708932276697, 2345.0971376020225, 2377.7146742390937, 2383.9987205373786, 2390.5816482115647, 2393.8922748476275, 2399.7120345558556, 2446.71862735785, 2513.45960618467, 2515.1197081021587, 2518.9496210075786, 2443.0838435586656, 2496.787773039294, 2508.949821003579, 2513.3339664988794, 2515.5975043992967, 2438.5366780311683, 2471.2462698876834, 2479.119912431088, 2480.06997375984, 2484.6769919910475, 2459.827812053157, 2470.8270421070533, 2480.34270918205, 2481.8138639602103, 2488.14159115105, 11229.831552526719, 11374.943125284404, 11391.0, 11391.886081139248, 11397.886021139766, 5949.672767997802, 6070.301915279755, 6136.662483563402, 6137.263528376623, 6139.87720245597, 3657.780533167992, 3759.3233218020746, 3788.583255841855, 3814.4278358246206, 3870.7290489665734, 1264.2477725753163, 1907.7138429235588, 1919.1267973072218, 1937.3509874192162, 1941.5825597496605, 13922.860771392257, 14173.0, 15524.379024838992, 15652.843471565253, 15951.0, 4203.936940945889, 4759.214729569651, 4795.136875362398, 4976.477469865685, 4992.076465853828, 11939.044876409866, 12028.218165819193, 12073.698157546132, 12095.516179352904, 12106.273623582612, 6057.0, 6069.393060693896, 6082.239720034996, 6090.482309003695, 6096.756129754849, 5347.51872331492, 5369.4362091980565, 5484.670919744828, 5542.944570554324, 5562.582806289463, 4011.7191796574543, 4058.9594104059174, 4102.2000709861295, 4138.979305103516, 4166.104287578185, 943.5659596585648, 943.8206740719241, 1083.6029048931657, 1089.5587287148717, 1091.3233795047122, 924.7595625137378, 942.0908822985851, 943.2783920300975, 966.6954909203579, 1003.2325271167563, 6749.358810912955, 6778.593284402951, 6792.694328755218, 6971.6165610891885, 7002.75490357835, 904.3669431398048, 1007.3250921882357, 1023.8976102389851, 1037.271974706517, 1044.3107549017611, 3995.860144894973, 4228.5982831630945, 4370.847020354272, 4489.775511224426, 4518.977405112953, 12989.675258118434, 13749.862501375059, 14384.640383990274, 14405.279736013157, 14424.639384015274, 13234.470621175238, 13493.32533373347, 13617.319134043259, 13653.795193072112, 13678.794818077737, 8980.46117232968, 10422.583096676048, 10556.683299501028, 10690.144788416908, 10739.892601073889, 6508.869822603616, 6899.586024838526, 7129.607871566906, 7226.168990565942, 7243.782686519413, 10095.394276343442, 12127.878721212675, 12154.392280385768, 12193.207441516273, 12206.511739530319, 10351.534180961724, 12813.679658008625, 13055.869441305465, 13118.868811311764, 13185.945124389835, 9105.635774568944, 9189.0, 9281.767955801159, 9285.07149285066, 9309.301802364986, 8950.731478055668, 10437.530311136168, 10440.791184176425, 10445.373277603065, 10556.788864222826, 8846.932296213872, 8986.05646407144, 8997.55012249398, 9170.770730731785, 9177.357584968988, 8644.481331120152, 8681.435542701673, 8728.51993140358, 8759.343049271203, 8871.689490867788, 7977.48146370484, 8079.030516338077, 8150.918490815253, 8155.877661834958, 8177.632006559838, 5183.144781111075, 5801.970990145022, 5928.199693041384, 6017.368176341596, 6055.788047418319, 5966.0, 5990.3710110439515, 6092.8781424372155, 6100.664463454553, 6241.500679945502], "value": 5310.410608232857, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.14660547712873, 364.6535790998549, 380.5718566612561, 400.8917592250094, 405.73627142357475, 4157.937630935538, 4850.660453768238, 4987.501249875011, 5289.942011597686, 5511.366192887819, 4525.718853830979, 4929.151568161939, 5210.218467229917, 5309.973450132744, 5332.173513105465, 4467.642588592913, 4658.301254811771, 4676.007355292615, 4709.835155769549, 4729.929051064237, 4036.41669331628, 4316.330968699841, 4327.1345730853955, 4430.778461076933, 4618.838340658078, 4259.913722000885, 4266.871993840175, 4319.632831209351, 4585.564371384713, 4615.238485649864, 3763.8494460221696, 4556.681032327739, 4595.471520775121, 4642.930356044662, 4675.322078298662, 3831.501904752364, 4420.734755914656, 4426.690131690783, 4436.0, 4451.755153466558, 4161.729487583298, 4860.816165411661, 5214.949254686013, 5218.373795144569, 5271.0, 4511.526289739563, 4988.850334489971, 4993.425756038066, 5159.948400515985, 5411.431799661018, 4949.554540091373, 5412.21271045412, 5583.35791383992, 5818.389069147764, 5821.75862293016, 4116.85923319875, 4381.3208952612395, 4426.933595996063, 4523.575073851726, 4858.951410485885, 5244.160934250502, 5453.536449401806, 5686.124717266615, 5826.251604941959, 5827.766889324422, 4240.575942405735, 4930.8767280818265, 4986.875328116754, 5008.323876276729, 5020.648554601216, 5076.340075790197, 5091.287219789232, 5092.34498787888, 5153.3558305211845, 5163.173892177234, 4275.593818587229, 4501.572350626685, 4894.95105048945, 5223.843284701465, 5295.576353891679, 5424.511793938565, 5682.431756824367, 5688.431156884279, 5693.946619875336, 5712.914306285409, 1465.3625672832352, 1646.4895882276533, 1734.3236137906226, 1742.32920325673, 1756.9297228110731, 1320.966975825612, 1368.3568722700315, 1384.5254803634098, 1391.7703578909568, 1401.976557113313, 5329.307190065267, 5453.7818487260065, 5459.863503412367, 5476.698781566973, 5488.588355873326, 5333.493318134771, 5545.861353466115, 5635.239242702265, 5935.792247271324, 5948.7323070461935, 5037.29477873098, 5139.922901156486, 5410.513053825096, 5439.558516993, 5492.615516913777, 2736.7421876951594, 2966.4067186562775, 3064.6016017917973, 3089.536569514546, 3089.969100309013, 5354.892902141935, 5457.590680698963, 6058.787942421994, 6097.5731698780655, 6187.628742275478, 5079.4412614612365, 5909.054551271776, 5935.495482883917, 5956.285245770535, 6112.524427606826, 5390.649607775482, 5613.158026296007, 5923.223033089969, 6192.721327540181, 6194.845128871814, 4717.693349932243, 5527.462424048532, 5813.331466881363, 5865.589408741518, 5981.910271345846, 5286.656367336111, 5345.438728933406, 5578.721063946868, 5736.852629474205, 5756.366799652119, 5324.627276090791, 5839.832033593383, 6127.509799216141, 6262.311145774052, 6299.2598219774545, 4557.521460246627, 5042.420121686053, 5152.6650767699975, 5162.1932323687915, 5227.163653815372, 5611.242482264842, 5938.138969849434, 5953.523718102455, 5974.0, 6039.909401358896, 5000.774965126505, 5380.946190538045, 5761.530809643514, 5845.970770146122, 5862.3844496328975, 5507.889842203213, 5613.550915926798, 5725.1908396946765, 5821.563382746226, 5910.931174089034, 5608.719564021864, 5672.914906276326, 5703.5722320826935, 5707.7454405510425, 5752.9712351437975, 5330.733463326741, 5516.738501800405, 5576.275084239023, 5807.303123625192, 5910.704464776658, 5206.453322401245, 5354.7858085676135, 5377.462253774747, 5713.742881570256, 5740.6268592541355], "value": 4470.841291445912, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.9999999999997, 754.3927138653382, 783.7570353190509, 823.7611092783089, 886.8714036464713, 16294.0, 17202.225899834506, 17432.825671743263, 17441.7383739244, 17465.301387944484, 13276.535321263758, 15855.92072039641, 16038.198090095508, 16063.598410039725, 16237.756433653505, 806.9677212911477, 980.8430651095827, 988.0514705882325, 1021.3718563083725, 1025.077430312717, 1008.5864795433873, 1020.367372229219, 1020.8060468510996, 1020.8723909511311, 1021.9131373833233, 1068.9251752377336, 1075.3332933581191, 1078.6817888722846, 1078.7680648660537, 1080.0171843622306, 1063.6702622187108, 1069.6095924987324, 1073.7637719701654, 1073.9463026848666, 1074.919381046417, 785.2187073861487, 789.1831953927657, 792.5007245435396, 796.7370767646663, 798.8601994650912, 16428.6714265714, 16899.23953422099, 17172.484825455256, 17271.0, 17511.64976700459, 14477.927610361987, 14716.70566588673, 14797.630059248497, 14834.258287085602, 14865.628359291104, 12000.699807552906, 12049.57826476078, 12061.698457538543, 12162.756744865143, 12320.691982700415, 3822.1591249924973, 3915.23652887687, 4054.553999060101, 4061.086255592468, 4062.1875624875142, 115144.0, 120989.0, 123453.76546234512, 123645.38177309146, 124223.3788831059, 11420.543178272776, 11425.257358271685, 11602.361870097056, 11627.12796540263, 11876.465559049864, 22988.380176780018, 23247.651285230735, 23303.41741456477, 23390.298291050956, 23632.0, 8247.752567422987, 8258.793530161674, 8274.958625206835, 8328.08391076981, 8339.416240863202, 4528.773561321921, 4577.313402989512, 4594.632429405639, 4640.0, 4645.256758918556, 3082.475979083561, 3715.9256814864093, 3726.329260733094, 3746.1945681678026, 3762.698984081267, 676.8375589858399, 685.1983179679787, 694.5034300475185, 697.5082566790401, 700.8142842146835, 514.9572116464116, 567.9148127780866, 578.528499273091, 585.420433770564, 610.4810910725936, 10684.916496072588, 10869.0, 10931.96146366094, 10973.780524389467, 11158.442077896236, 642.874639445313, 690.6443181761397, 703.2545501768142, 732.4323649171828, 733.8935854301096, 4039.838406463767, 4844.716150220194, 5064.746762661852, 5163.948360516422, 5173.560247379015, 101541.0, 109204.0, 115072.0, 116330.41834790772, 118259.0, 71682.0, 96722.0, 98757.0, 100340.0, 102782.0, 73773.0, 78132.0, 80499.0, 82564.0, 84473.0, 11098.223124381217, 11226.270292430965, 11234.719132021764, 11303.604373846876, 11453.656390308304, 48742.0, 49207.0, 50550.0, 51432.74283628558, 52008.0, 71383.0, 71598.64200678964, 72597.0, 75879.62060189663, 79790.0, 10613.257072005194, 11560.843915608371, 12347.931873479361, 13341.443835602748, 13903.513377031755, 15413.691726165638, 15603.921980390025, 15710.214489275719, 15842.287097080889, 16398.26207820675, 13989.510367137102, 14152.78770818459, 14457.493987710379, 14550.199739014033, 15000.0, 13379.933100334436, 13396.866031339952, 13835.930820345833, 14034.0, 14405.42378304898, 13587.388567514683, 14045.438182472586, 14276.000679952303, 14310.928445358122, 14316.0, 10122.544485498023, 10440.37357758537, 10535.525901334611, 10641.467926603793, 11185.32888026721, 10122.486940003155, 11257.04315133223, 11434.313941163557, 11668.533258669559, 11706.239094458833], "value": 8022.382949352645, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 118.0, 119.0, 119.0, 119.0, 117.0, 136.0, 174.0, 164.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 100.0, 97.0, 153.0, 127.0, 139.0, 138.0, 110.0, 127.0, 112.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 198.0, 194.0, 206.0, 231.0, 276.0, 273.0, 198.0, 196.0, 200.0], "value": 217.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 64.0, 83.0, 111.0, 53.0, 63.0, 85.0, 86.0, 98.0, 85.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 83.0, 90.0, 56.0, 73.0, 110.0, 101.0, 100.0, 112.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 96.0, 129.0, 92.0, 91.0, 91.0, 93.0, 91.0, 92.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 129.0, 125.0, 153.0, 139.0, 97.0, 138.0, 149.0, 97.0, 98.0], "value": 122.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 104.0, 140.0, 150.0, 91.0, 89.0, 88.0, 92.0, 92.0, 91.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 69.0, 68.0, 68.0, 67.0, 69.0, 69.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 59.0, 67.0, 70.0, 63.0, 62.0, 58.0, 58.0, 57.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 86.0, 83.0, 84.0, 84.0, 85.0, 86.0, 86.0, 89.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 143.0, 143.0, 137.0, 139.0, 136.0, 140.0, 145.0, 176.0, 149.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 166.0, 215.0, 173.0, 148.0, 222.0, 221.0, 177.0, 170.0], "value": 182.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 66.0, 66.0, 65.0, 64.0, 65.0, 63.0, 65.0], "value": 65.4, "unit": "score"}]}]} 09:44:37 INFO - Return code: 0 09:44:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:37 ERROR - # TBPL SUCCESS # 09:44:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:37 INFO - 2017-04-21 09:44:37.585 screenresolution[1660:10505] starting screenresolution argv=screenresolution get 09:44:37 INFO - 2017-04-21 09:44:37.588 screenresolution[1660:10505] Display 0: 1600x1200x32@60 09:44:37 INFO - 2017-04-21 09:44:37.593 screenresolution[1661:10508] starting screenresolution argv=screenresolution list 09:44:37 INFO - Available Modes on Display 0 09:44:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:37 INFO - Intel Iris: 09:44:37 INFO - Chipset Model: Intel Iris 09:44:37 INFO - Type: GPU 09:44:37 INFO - Bus: Built-In 09:44:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:37 INFO - Vendor: Intel (0x8086) 09:44:37 INFO - Device ID: 0x0a2e 09:44:37 INFO - Revision ID: 0x0009 09:44:37 INFO - Displays: 09:44:37 INFO - Display: 09:44:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:37 INFO - Display Serial Number: 1600x1200 60 09:44:37 INFO - Main Display: Yes 09:44:37 INFO - Mirror: Off 09:44:37 INFO - Online: Yes 09:44:37 INFO - Rotation: Supported 09:44:37 INFO - Return code: 0 09:44:37 INFO - Running post-action listener: _resource_record_post_action 09:44:37 INFO - [mozharness: 2017-04-21 16:44:37.829150Z] Finished run-tests step (success) 09:44:37 INFO - Running post-run listener: _resource_record_post_run 09:44:37 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 40724480; Write bytes: 427896832; Read time: 399; Write time: 1846 09:44:37 INFO - TinderboxPrint: CPU usage
54.5% 09:44:37 INFO - TinderboxPrint: I/O read bytes / time
40,724,480 / 399 09:44:37 INFO - TinderboxPrint: I/O write bytes / time
427,896,832 / 1,846 09:44:37 INFO - TinderboxPrint: CPU idle
2,173.7 (45.5%) 09:44:37 INFO - TinderboxPrint: CPU system
961.8 (20.1%) 09:44:37 INFO - TinderboxPrint: CPU user
1,638.2 (34.3%) 09:44:37 INFO - TinderboxPrint: Swap in / out
163,143,680 / 0 09:44:37 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119568896; Write bytes: 116729856; Read time: 10904; Write time: 310 09:44:37 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 38647808; Write bytes: 300185600; Read time: 375; Write time: 1506 09:44:38 INFO - Running post-run listener: _upload_blobber_files 09:44:38 INFO - Blob upload gear active. 09:44:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:38 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 09:44:38 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'] 09:44:38 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 09:44:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:44:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:44:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:39 INFO - (blobuploader) - INFO - Done attempting. 09:44:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:44:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:44:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:40 INFO - (blobuploader) - INFO - Done attempting. 09:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:41 INFO - (blobuploader) - INFO - Done attempting. 09:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:44 INFO - (blobuploader) - INFO - Done attempting. 09:44:44 INFO - (blobuploader) - INFO - Iteration through files over. 09:44:44 INFO - Return code: 0 09:44:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:44:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:44:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ce68d16c0bde69ad1c2b10f75626ff5c3bf933eac59fc73461da03fb1399c3b924e2cb185586c0d812fd3da3b10bed42136aec7c192bd2649d91fd7c0ce121d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f81755cb62add68cc2ab70e7d291a4926ee05872ee3acfc4b37b0d3c37dff6eae86ec52734813f0076bff2138eae75f6cd3a9e1859e91e8066904904e12d04af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6fbc555f27aeebda1ad3866179ff34d527aefc0ba9fe1e7f56044da89ddd992f27771e8181951e1ac9f6b7b92b13b9ffed1475c16c4cf4eddf5c12436007d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5662cf30863b75c942db69eab2f9b67285ef3c2f67b169d44e5fcb531313ecf2805ccf08c9b8d49270945818f398f43e61b0570e08c547b9aeffffb01f767b"} 09:44:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:44:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:44:44 INFO - Contents: 09:44:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ce68d16c0bde69ad1c2b10f75626ff5c3bf933eac59fc73461da03fb1399c3b924e2cb185586c0d812fd3da3b10bed42136aec7c192bd2649d91fd7c0ce121d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f81755cb62add68cc2ab70e7d291a4926ee05872ee3acfc4b37b0d3c37dff6eae86ec52734813f0076bff2138eae75f6cd3a9e1859e91e8066904904e12d04af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6fbc555f27aeebda1ad3866179ff34d527aefc0ba9fe1e7f56044da89ddd992f27771e8181951e1ac9f6b7b92b13b9ffed1475c16c4cf4eddf5c12436007d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5662cf30863b75c942db69eab2f9b67285ef3c2f67b169d44e5fcb531313ecf2805ccf08c9b8d49270945818f398f43e61b0570e08c547b9aeffffb01f767b"} 09:44:44 INFO - Running post-run listener: copy_logs_to_upload_dir 09:44:44 INFO - Copying logs to upload dir... 09:44:44 INFO - mkdir: /builds/slave/test/build/upload/logs 09:44:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.553384 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-04-21 09:44:44.274358) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 09:44:44.278293) ========= 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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/3ce68d16c0bde69ad1c2b10f75626ff5c3bf933eac59fc73461da03fb1399c3b924e2cb185586c0d812fd3da3b10bed42136aec7c192bd2649d91fd7c0ce121d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f81755cb62add68cc2ab70e7d291a4926ee05872ee3acfc4b37b0d3c37dff6eae86ec52734813f0076bff2138eae75f6cd3a9e1859e91e8066904904e12d04af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6fbc555f27aeebda1ad3866179ff34d527aefc0ba9fe1e7f56044da89ddd992f27771e8181951e1ac9f6b7b92b13b9ffed1475c16c4cf4eddf5c12436007d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5662cf30863b75c942db69eab2f9b67285ef3c2f67b169d44e5fcb531313ecf2805ccf08c9b8d49270945818f398f43e61b0570e08c547b9aeffffb01f767b"} build_url:https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011682 build_url: 'https://queue.taskcluster.net/v1/task/CQTPRRnESwS8df7gqZf0qQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ce68d16c0bde69ad1c2b10f75626ff5c3bf933eac59fc73461da03fb1399c3b924e2cb185586c0d812fd3da3b10bed42136aec7c192bd2649d91fd7c0ce121d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f81755cb62add68cc2ab70e7d291a4926ee05872ee3acfc4b37b0d3c37dff6eae86ec52734813f0076bff2138eae75f6cd3a9e1859e91e8066904904e12d04af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6fbc555f27aeebda1ad3866179ff34d527aefc0ba9fe1e7f56044da89ddd992f27771e8181951e1ac9f6b7b92b13b9ffed1475c16c4cf4eddf5c12436007d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bb5662cf30863b75c942db69eab2f9b67285ef3c2f67b169d44e5fcb531313ecf2805ccf08c9b8d49270945818f398f43e61b0570e08c547b9aeffffb01f767b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 09:44:44.314169) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:44:44.314515) ========= 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.jVqEITsCVH/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.r4uUMVhmPJ/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005399 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 09:44:44.367775) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 09:44:44.370140) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 09:44:44.370494) ========= ========= Total master_lag: 0.22 =========