builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0181 starttime: 1485353223.94 results: success (0) buildid: 20170125044851 builduid: bd17f27b839540f1aaf91cdf2da8eef7 revision: c989c7b352279925edf138373e4ca3f1540dbd5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:03.945100) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:03.945531) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:03.945814) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:03.971012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:03.971450) ========= 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.65oNHFqSC2/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.V2WdmIjwKT/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-25 06:07:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.6M=0.001s 2017-01-25 06:07:04 (12.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:04.132175) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:04.132498) ========= 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.65oNHFqSC2/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.V2WdmIjwKT/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.021505 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:04.184718) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 06:07:04.185118) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c989c7b352279925edf138373e4ca3f1540dbd5f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c989c7b352279925edf138373e4ca3f1540dbd5f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.65oNHFqSC2/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.V2WdmIjwKT/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-25 06:07:04,270 truncating revision to first 12 chars 2017-01-25 06:07:04,270 Setting DEBUG logging. 2017-01-25 06:07:04,270 attempt 1/10 2017-01-25 06:07:04,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c989c7b35227?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-25 06:07:05,836 unpacking tar archive at: mozilla-central-c989c7b35227/testing/mozharness/ program finished with exit code 0 elapsedTime=1.826944 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 06:07:06.035351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:06.035708) ========= script_repo_revision: c989c7b352279925edf138373e4ca3f1540dbd5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:06.036124) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:06.036405) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 06:07:06.056515) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-01-25 06:07:06.056893) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.65oNHFqSC2/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.V2WdmIjwKT/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:06 INFO - MultiFileLogger online at 20170125 06:07:06 in /builds/slave/test 06:07:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:07:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:07:06 INFO - {'append_to_log': False, 06:07:06 INFO - 'base_work_dir': '/builds/slave/test', 06:07:06 INFO - 'blob_upload_branch': 'Firefox', 06:07:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:07:06 INFO - 'branch': 'Firefox', 06:07:06 INFO - 'buildbot_json_path': 'buildprops.json', 06:07:06 INFO - 'config_files': ('talos/mac_config.py',), 06:07:06 INFO - 'default_actions': ('clobber', 06:07:06 INFO - 'read-buildbot-config', 06:07:06 INFO - 'download-and-extract', 06:07:06 INFO - 'populate-webroot', 06:07:06 INFO - 'create-virtualenv', 06:07:06 INFO - 'install', 06:07:06 INFO - 'run-tests'), 06:07:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:07:06 INFO - 'download_minidump_stackwalk': True, 06:07:06 INFO - 'download_symbols': 'ondemand', 06:07:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:07:06 INFO - 'tooltool.py': '/tools/tooltool.py', 06:07:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:07:06 INFO - '/tools/misc-python/virtualenv.py')}, 06:07:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:07:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:07:06 INFO - 'installer_path': 'installer.exe', 06:07:06 INFO - 'log_level': 'info', 06:07:06 INFO - 'log_name': 'talos', 06:07:06 INFO - 'log_to_console': True, 06:07:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:07:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:07:06 INFO - 'opt_config_files': (), 06:07:06 INFO - 'pip_index': False, 06:07:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:06 INFO - 'cmd': ('bash', 06:07:06 INFO - '-c', 06:07:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:06 INFO - 'enabled': True, 06:07:06 INFO - 'halt_on_failure': False, 06:07:06 INFO - 'name': 'check_screen_resolution'},), 06:07:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:06 INFO - 'cmd': ('bash', 06:07:06 INFO - '-c', 06:07:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:06 INFO - 'enabled': True, 06:07:06 INFO - 'halt_on_failure': False, 06:07:06 INFO - 'name': 'check_screen_resolution'},), 06:07:06 INFO - 'run_cmd_checks_enabled': True, 06:07:06 INFO - 'sps_profile': False, 06:07:06 INFO - 'sps_profile_interval': 0, 06:07:06 INFO - 'suite': 'dromaeojs-e10s', 06:07:06 INFO - 'system_bits': '32', 06:07:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:07:06 INFO - 'title': 't-yosemite-r7-0181', 06:07:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:07:06 INFO - 'use_talos_json': True, 06:07:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:07:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:07:06 INFO - 'work_dir': 'build'} 06:07:06 INFO - [mozharness: 2017-01-25 14:07:06.264871Z] Running clobber step. 06:07:06 INFO - Running pre-action listener: _resource_record_pre_action 06:07:06 INFO - Running main action method: clobber 06:07:06 INFO - rmtree: /builds/slave/test/build 06:07:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:07:08 INFO - Running post-action listener: _resource_record_post_action 06:07:08 INFO - [mozharness: 2017-01-25 14:07:08.077597Z] Finished clobber step (success) 06:07:08 INFO - [mozharness: 2017-01-25 14:07:08.077776Z] Running read-buildbot-config step. 06:07:08 INFO - Running pre-action listener: _resource_record_pre_action 06:07:08 INFO - Running main action method: read_buildbot_config 06:07:08 INFO - Using buildbot properties: 06:07:08 INFO - { 06:07:08 INFO - "project": "", 06:07:08 INFO - "product": "firefox", 06:07:08 INFO - "script_repo_revision": "production", 06:07:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:07:08 INFO - "repository": "", 06:07:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:07:08 INFO - "buildid": "20170125044851", 06:07:08 INFO - "pgo_build": "False", 06:07:08 INFO - "basedir": "/builds/slave/test", 06:07:08 INFO - "buildnumber": 130, 06:07:08 INFO - "slavename": "t-yosemite-r7-0181", 06:07:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 06:07:08 INFO - "platform": "yosemite_r7", 06:07:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:07:08 INFO - "branch": "mozilla-central", 06:07:08 INFO - "repo_path": "mozilla-central", 06:07:08 INFO - "revision": "c989c7b352279925edf138373e4ca3f1540dbd5f", 06:07:08 INFO - "stage_platform": "macosx64", 06:07:08 INFO - "builduid": "bd17f27b839540f1aaf91cdf2da8eef7", 06:07:08 INFO - "slavebuilddir": "test" 06:07:08 INFO - } 06:07:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:07:08 INFO - Running post-action listener: _resource_record_post_action 06:07:08 INFO - [mozharness: 2017-01-25 14:07:08.081895Z] Finished read-buildbot-config step (success) 06:07:08 INFO - [mozharness: 2017-01-25 14:07:08.082026Z] Running download-and-extract step. 06:07:08 INFO - Running pre-action listener: _resource_record_pre_action 06:07:08 INFO - Running main action method: download_and_extract 06:07:08 INFO - mkdir: /builds/slave/test/build/tests 06:07: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')]} 06:07:08 INFO - trying https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:07:08 INFO - Downloading https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:07:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:07:09 INFO - Downloaded 1199 bytes. 06:07:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:07:09 INFO - Using the following test package requirements: 06:07:09 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:07:09 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:07:09 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:07:09 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'jsshell-mac.zip'], 06:07:09 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:07:09 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:07:09 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:07:09 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:07:09 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:07:09 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:09 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:07:09 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:07:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:07:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:09 INFO - Fetch https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:07:10 INFO - Expected file size: 16351534 06:07:10 INFO - Obtained file size: 16351534 06:07:16 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:07:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:07:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:16 INFO - Fetch https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:07:17 INFO - Expected file size: 16351534 06:07:17 INFO - Obtained file size: 16351534 06:07:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:07:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:07:24 INFO - Fetch https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:07:25 INFO - Expected file size: 14403435 06:07:25 INFO - Obtained file size: 14403435 06:07:25 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')]} 06:07:25 INFO - trying https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:07:25 INFO - Downloading https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:07:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:07:27 INFO - Downloaded 69896514 bytes. 06:07:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:07:27 INFO - mkdir: /builds/slave/test/properties 06:07:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:07:27 INFO - Writing to file /builds/slave/test/properties/build_url 06:07:27 INFO - Contents: 06:07:27 INFO - build_url:https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:07:28 INFO - Running post-action listener: _resource_record_post_action 06:07:28 INFO - Running post-action listener: set_extra_try_arguments 06:07:28 INFO - [mozharness: 2017-01-25 14:07:28.030010Z] Finished download-and-extract step (success) 06:07:28 INFO - [mozharness: 2017-01-25 14:07:28.030130Z] Running populate-webroot step. 06:07:28 INFO - Running pre-action listener: _resource_record_pre_action 06:07:28 INFO - Running main action method: populate_webroot 06:07:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:07:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:07:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:07:28 INFO - '--tppagecycles', 06:07:28 INFO - '7'], 06:07:28 INFO - 'tests': ['tsvgm']}}, 06:07:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:07:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:07:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:07:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:07:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['damp', 'tps']}, 06:07:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'tests': ['damp', 'tps']}, 06:07:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['dromaeo_dom']}, 06:07:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:07:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['basic_compositor_video']}, 06:07:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:07:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['a11yr', 06:07:28 INFO - 'ts_paint', 06:07:28 INFO - 'tpaint', 06:07:28 INFO - 'sessionrestore', 06:07:28 INFO - 'sessionrestore_no_auto_restore', 06:07:28 INFO - 'tabpaint']}, 06:07:28 INFO - 'other-e10s': {'tests': ['a11yr', 06:07:28 INFO - 'ts_paint', 06:07:28 INFO - 'tpaint', 06:07:28 INFO - 'sessionrestore', 06:07:28 INFO - 'sessionrestore_no_auto_restore', 06:07:28 INFO - 'tabpaint']}, 06:07:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:07:28 INFO - 'ts_paint', 06:07:28 INFO - 'tpaint', 06:07:28 INFO - 'sessionrestore', 06:07:28 INFO - 'sessionrestore_no_auto_restore', 06:07:28 INFO - 'tabpaint']}, 06:07:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:07:28 INFO - 'ts_paint', 06:07:28 INFO - 'tpaint', 06:07:28 INFO - 'sessionrestore', 06:07:28 INFO - 'sessionrestore_no_auto_restore', 06:07:28 INFO - 'tabpaint']}, 06:07:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['a11yr', 06:07:28 INFO - 'ts_paint', 06:07:28 INFO - 'tpaint', 06:07:28 INFO - 'sessionrestore', 06:07:28 INFO - 'sessionrestore_no_auto_restore', 06:07:28 INFO - 'tabpaint']}, 06:07:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['a11yr', 06:07:28 INFO - 'ts_paint', 06:07:28 INFO - 'tpaint', 06:07:28 INFO - 'sessionrestore', 06:07:28 INFO - 'sessionrestore_no_auto_restore', 06:07:28 INFO - 'tabpaint']}, 06:07:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['tsvgx', 06:07:28 INFO - 'tsvgr_opacity', 06:07:28 INFO - 'tart', 06:07:28 INFO - 'tscrollx', 06:07:28 INFO - 'cart', 06:07:28 INFO - 'tsvg_static']}, 06:07:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:07:28 INFO - 'tsvgr_opacity', 06:07:28 INFO - 'tart', 06:07:28 INFO - 'tscrollx', 06:07:28 INFO - 'cart', 06:07:28 INFO - 'tsvg_static']}, 06:07:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'talos_options': ['--disable-e10s'], 06:07:28 INFO - 'tests': ['tp5o']}, 06:07:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:07:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'talos_options': ['--disable-e10s', 06:07:28 INFO - '--xperf_path', 06:07:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:28 INFO - 'tests': ['tp5n']}, 06:07:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:07:28 INFO - 'talos_options': ['--xperf_path', 06:07:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:28 INFO - 'tests': ['tp5n']}}, 06:07:28 INFO - 'talos.zip': {'path': '', 06:07:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:28 INFO - Running post-action listener: _resource_record_post_action 06:07:28 INFO - [mozharness: 2017-01-25 14:07:28.048089Z] Finished populate-webroot step (success) 06:07:28 INFO - [mozharness: 2017-01-25 14:07:28.048216Z] Running create-virtualenv step. 06:07:28 INFO - Running pre-action listener: _resource_record_pre_action 06:07:28 INFO - Running main action method: create_virtualenv 06:07:28 INFO - Creating virtualenv /builds/slave/test/build/venv 06:07:28 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 06:07:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:07:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:07:28 INFO - Using real prefix '/tools/python27' 06:07:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:07:28 INFO - Installing distribute.............................................................................................................................................................................................done. 06:07:31 INFO - Installing pip.................done. 06:07:31 INFO - Return code: 0 06:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:07:32 INFO - Reading from file tmpfile_stdout 06:07:32 INFO - Output received: 06:07:32 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) 06:07:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:07:32 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')]} 06:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:32 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')]} 06:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:32 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 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:32 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 06:07:32 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 06:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:32 INFO - 'HOME': '/Users/cltbld', 06:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:32 INFO - 'LOGNAME': 'cltbld', 06:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:32 INFO - 'MOZ_NO_REMOTE': '1', 06:07:32 INFO - 'NO_EM_RESTART': '1', 06:07:32 INFO - 'PAGER': '/bin/cat', 06:07:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:32 INFO - 'PWD': '/builds/slave/test', 06:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:32 INFO - 'SHELL': '/bin/bash', 06:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:32 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:32 INFO - 'USER': 'cltbld', 06:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:32 INFO - 'XPC_FLAGS': '0x0', 06:07:32 INFO - 'XPC_SERVICE_NAME': '0', 06:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:32 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 06:07:32 INFO - Cleaning up... 06:07:32 INFO - Return code: 0 06:07:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:07:32 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')]} 06:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:32 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')]} 06:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:32 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 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:32 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 06:07:32 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 06:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:32 INFO - 'HOME': '/Users/cltbld', 06:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:32 INFO - 'LOGNAME': 'cltbld', 06:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:32 INFO - 'MOZ_NO_REMOTE': '1', 06:07:32 INFO - 'NO_EM_RESTART': '1', 06:07:32 INFO - 'PAGER': '/bin/cat', 06:07:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:32 INFO - 'PWD': '/builds/slave/test', 06:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:32 INFO - 'SHELL': '/bin/bash', 06:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:32 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:32 INFO - 'USER': 'cltbld', 06:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:32 INFO - 'XPC_FLAGS': '0x0', 06:07:32 INFO - 'XPC_SERVICE_NAME': '0', 06:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:32 INFO - Downloading/unpacking psutil>=3.1.1 06:07: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 06:07: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 06:07: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 06:07: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 06:07: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 06:07: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 06:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:07:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:36 INFO - Installing collected packages: psutil 06:07:36 INFO - Running setup.py install for psutil 06:07:36 INFO - building 'psutil._psutil_osx' extension 06:07:36 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 06:07:36 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 06:07:36 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 06:07:36 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 06:07:36 INFO - building 'psutil._psutil_posix' extension 06:07:36 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 06:07:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:07:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:07:36 INFO - ^ 06:07:36 INFO - 1 warning generated. 06:07:36 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 06:07:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:36 INFO - Successfully installed psutil 06:07:36 INFO - Cleaning up... 06:07:37 INFO - Return code: 0 06:07:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:37 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 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:37 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 06:07:37 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 06:07:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:37 INFO - 'HOME': '/Users/cltbld', 06:07:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:37 INFO - 'LOGNAME': 'cltbld', 06:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:37 INFO - 'MOZ_NO_REMOTE': '1', 06:07:37 INFO - 'NO_EM_RESTART': '1', 06:07:37 INFO - 'PAGER': '/bin/cat', 06:07:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:37 INFO - 'PWD': '/builds/slave/test', 06:07:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:37 INFO - 'SHELL': '/bin/bash', 06:07:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:37 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:37 INFO - 'USER': 'cltbld', 06:07:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:37 INFO - 'XPC_FLAGS': '0x0', 06:07:37 INFO - 'XPC_SERVICE_NAME': '0', 06:07:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:07:39 INFO - Installing collected packages: mozsystemmonitor 06:07:39 INFO - Running setup.py install for mozsystemmonitor 06:07:40 INFO - Successfully installed mozsystemmonitor 06:07:40 INFO - Cleaning up... 06:07:40 INFO - Return code: 0 06:07:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:40 INFO - 'HOME': '/Users/cltbld', 06:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:40 INFO - 'LOGNAME': 'cltbld', 06:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:40 INFO - 'MOZ_NO_REMOTE': '1', 06:07:40 INFO - 'NO_EM_RESTART': '1', 06:07:40 INFO - 'PAGER': '/bin/cat', 06:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:40 INFO - 'PWD': '/builds/slave/test', 06:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:40 INFO - 'SHELL': '/bin/bash', 06:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:40 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:40 INFO - 'USER': 'cltbld', 06:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:40 INFO - 'XPC_FLAGS': '0x0', 06:07:40 INFO - 'XPC_SERVICE_NAME': '0', 06:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:40 INFO - Downloading/unpacking jsonschema==2.5.1 06:07: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 06:07: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 06:07: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 06:07: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 06:07: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 06:07: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 06:07:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:43 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:07:43 INFO - warning: no files found matching '*.txt' 06:07:43 INFO - no previously-included directories found matching 'build' 06:07:43 INFO - no previously-included directories found matching 'dist' 06:07:43 INFO - no previously-included directories found matching '.git*' 06:07:43 INFO - Installing collected packages: jsonschema, functools32 06:07:43 INFO - Running setup.py install for functools32 06:07:43 INFO - warning: no files found matching '*.txt' 06:07:43 INFO - no previously-included directories found matching 'build' 06:07:43 INFO - no previously-included directories found matching 'dist' 06:07:43 INFO - no previously-included directories found matching '.git*' 06:07:43 INFO - Successfully installed jsonschema functools32 06:07:43 INFO - Cleaning up... 06:07:43 INFO - Return code: 0 06:07:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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', '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 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:07: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 functools32==3.2.3-2 06:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:43 INFO - 'HOME': '/Users/cltbld', 06:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:43 INFO - 'LOGNAME': 'cltbld', 06:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:43 INFO - 'MOZ_NO_REMOTE': '1', 06:07:43 INFO - 'NO_EM_RESTART': '1', 06:07:43 INFO - 'PAGER': '/bin/cat', 06:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:43 INFO - 'PWD': '/builds/slave/test', 06:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:43 INFO - 'SHELL': '/bin/bash', 06:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:43 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:43 INFO - 'USER': 'cltbld', 06:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:43 INFO - 'XPC_FLAGS': '0x0', 06:07:43 INFO - 'XPC_SERVICE_NAME': '0', 06:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:07:44 INFO - Cleaning up... 06:07:44 INFO - Return code: 0 06:07:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:07:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:44 INFO - 'HOME': '/Users/cltbld', 06:07:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:44 INFO - 'LOGNAME': 'cltbld', 06:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:44 INFO - 'MOZ_NO_REMOTE': '1', 06:07:44 INFO - 'NO_EM_RESTART': '1', 06:07:44 INFO - 'PAGER': '/bin/cat', 06:07:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:44 INFO - 'PWD': '/builds/slave/test', 06:07:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:44 INFO - 'SHELL': '/bin/bash', 06:07:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:44 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:44 INFO - 'USER': 'cltbld', 06:07:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:44 INFO - 'XPC_FLAGS': '0x0', 06:07:44 INFO - 'XPC_SERVICE_NAME': '0', 06:07:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:44 INFO - Downloading/unpacking blobuploader==1.2.4 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:47 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:07:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:07:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:48 INFO - Downloading docopt-0.6.1.tar.gz 06:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:07:48 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:48 INFO - Running setup.py install for blobuploader 06:07:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:07:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:07:48 INFO - Running setup.py install for requests 06:07:49 INFO - Running setup.py install for docopt 06:07:49 INFO - Successfully installed blobuploader requests docopt 06:07:49 INFO - Cleaning up... 06:07:49 INFO - Return code: 0 06:07:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:49 INFO - 'HOME': '/Users/cltbld', 06:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:49 INFO - 'LOGNAME': 'cltbld', 06:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:49 INFO - 'MOZ_NO_REMOTE': '1', 06:07:49 INFO - 'NO_EM_RESTART': '1', 06:07:49 INFO - 'PAGER': '/bin/cat', 06:07:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:49 INFO - 'PWD': '/builds/slave/test', 06:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:49 INFO - 'SHELL': '/bin/bash', 06:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:49 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:49 INFO - 'USER': 'cltbld', 06:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:49 INFO - 'XPC_FLAGS': '0x0', 06:07:49 INFO - 'XPC_SERVICE_NAME': '0', 06:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:49 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-TciKbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:49 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-BoZ7TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:49 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-GjHYdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:49 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-zFxnZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:49 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-wIns8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-lhFrOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ZohbLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ZrrHMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-vgP57p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Al9ZRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-w5m607-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-IOQs_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-PWPQcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4GhlUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:50 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Pyuf8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:51 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-P7Y3ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:51 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JhyCsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:07:51 INFO - Running setup.py install for manifestparser 06:07:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:07:51 INFO - Running setup.py install for mozcrash 06:07:51 INFO - Running setup.py install for mozdebug 06:07:51 INFO - Running setup.py install for mozdevice 06:07:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:07:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:07:51 INFO - Running setup.py install for mozfile 06:07:52 INFO - Running setup.py install for mozhttpd 06:07:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Running setup.py install for mozinfo 06:07:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Running setup.py install for mozInstall 06:07:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Running setup.py install for mozleak 06:07:52 INFO - Running setup.py install for mozlog 06:07:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Running setup.py install for moznetwork 06:07:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:07:52 INFO - Running setup.py install for mozprocess 06:07:53 INFO - Running setup.py install for mozprofile 06:07:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:07:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:07:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:07:53 INFO - Running setup.py install for mozrunner 06:07:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:07:53 INFO - Running setup.py install for mozscreenshot 06:07:53 INFO - Running setup.py install for moztest 06:07:53 INFO - Running setup.py install for mozversion 06:07:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:07:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:07:53 INFO - Cleaning up... 06:07:53 INFO - Return code: 0 06:07:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:53 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 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:53 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 06:07:53 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 06:07:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:53 INFO - 'HOME': '/Users/cltbld', 06:07:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:53 INFO - 'LOGNAME': 'cltbld', 06:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:53 INFO - 'MOZ_NO_REMOTE': '1', 06:07:53 INFO - 'NO_EM_RESTART': '1', 06:07:53 INFO - 'PAGER': '/bin/cat', 06:07:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:53 INFO - 'PWD': '/builds/slave/test', 06:07:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:53 INFO - 'SHELL': '/bin/bash', 06:07:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:53 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:53 INFO - 'USER': 'cltbld', 06:07:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:53 INFO - 'XPC_FLAGS': '0x0', 06:07:53 INFO - 'XPC_SERVICE_NAME': '0', 06:07:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-3VkL0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1RfA3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-MRuU5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-vco09Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mAXUV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:54 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)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-FKUdYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:54 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)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-MtE0dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:54 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)) 06:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:54 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_8wjPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-kWXKWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-GXxB4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-8tcgCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bREv0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-O7BfZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-k370GA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-IZ_ZQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:55 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)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Zuwvb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:55 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)) 06:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:55 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_iOJfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 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)) 06:07:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:58 INFO - Downloading blessings-1.6.tar.gz 06:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:07:58 INFO - Installing collected packages: blessings 06:07:58 INFO - Running setup.py install for blessings 06:07:58 INFO - Successfully installed blessings 06:07:58 INFO - Cleaning up... 06:07:58 INFO - Return code: 0 06:07:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:07:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:59 INFO - Reading from file tmpfile_stdout 06:07:59 INFO - Current package versions: 06:07:59 INFO - blessings == 1.6 06:07:59 INFO - blobuploader == 1.2.4 06:07:59 INFO - docopt == 0.6.1 06:07:59 INFO - functools32 == 3.2.3-2 06:07:59 INFO - jsonschema == 2.5.1 06:07:59 INFO - manifestparser == 1.1 06:07:59 INFO - mozInstall == 1.12 06:07:59 INFO - mozcrash == 1.0 06:07:59 INFO - mozdebug == 0.1 06:07:59 INFO - mozdevice == 0.49 06:07:59 INFO - mozfile == 1.2 06:07:59 INFO - mozhttpd == 0.7 06:07:59 INFO - mozinfo == 0.9 06:07:59 INFO - mozleak == 0.1 06:07:59 INFO - mozlog == 3.4 06:07:59 INFO - moznetwork == 0.27 06:07:59 INFO - mozprocess == 0.24 06:07:59 INFO - mozprofile == 0.28 06:07:59 INFO - mozrunner == 6.13 06:07:59 INFO - mozscreenshot == 0.1 06:07:59 INFO - mozsystemmonitor == 0.3 06:07:59 INFO - moztest == 0.8 06:07:59 INFO - mozversion == 1.4 06:07:59 INFO - psutil == 3.1.1 06:07:59 INFO - requests == 1.2.3 06:07:59 INFO - wsgiref == 0.1.2 06:07:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:59 INFO - 'HOME': '/Users/cltbld', 06:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:59 INFO - 'LOGNAME': 'cltbld', 06:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:59 INFO - 'MOZ_NO_REMOTE': '1', 06:07:59 INFO - 'NO_EM_RESTART': '1', 06:07:59 INFO - 'PAGER': '/bin/cat', 06:07:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:59 INFO - 'PWD': '/builds/slave/test', 06:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:59 INFO - 'SHELL': '/bin/bash', 06:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:07:59 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:59 INFO - 'USER': 'cltbld', 06:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:59 INFO - 'XPC_FLAGS': '0x0', 06:07:59 INFO - 'XPC_SERVICE_NAME': '0', 06:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 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)) 06:08:00 INFO - Cleaning up... 06:08:00 INFO - Return code: 0 06:08:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:08:00 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')]} 06:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:08:00 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')]} 06:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:08:00 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 0x1048f0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048f3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe2a48f450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, '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.65oNHFqSC2/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.V2WdmIjwKT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 06:08:00 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 06:08:00 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 06:08:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:08:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:00 INFO - 'HOME': '/Users/cltbld', 06:08:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:00 INFO - 'LOGNAME': 'cltbld', 06:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:00 INFO - 'MOZ_NO_REMOTE': '1', 06:08:00 INFO - 'NO_EM_RESTART': '1', 06:08:00 INFO - 'PAGER': '/bin/cat', 06:08:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:00 INFO - 'PWD': '/builds/slave/test', 06:08:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:00 INFO - 'SHELL': '/bin/bash', 06:08:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:08:00 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:08:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:00 INFO - 'USER': 'cltbld', 06:08:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:00 INFO - 'XPC_FLAGS': '0x0', 06:08:00 INFO - 'XPC_SERVICE_NAME': '0', 06:08:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:08:00 INFO - Cleaning up... 06:08:00 INFO - Return code: 0 06:08:00 INFO - Running post-action listener: _resource_record_post_action 06:08:00 INFO - Running post-action listener: _start_resource_monitoring 06:08:01 INFO - Starting resource monitoring. 06:08:01 INFO - [mozharness: 2017-01-25 14:08:01.021886Z] Finished create-virtualenv step (success) 06:08:01 INFO - [mozharness: 2017-01-25 14:08:01.022905Z] Running install step. 06:08:01 INFO - Running pre-action listener: _resource_record_pre_action 06:08:01 INFO - Running main action method: install 06:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:08:01 INFO - Reading from file tmpfile_stdout 06:08:01 INFO - Detecting whether we're running mozinstall >=1.0... 06:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:08:01 INFO - Reading from file tmpfile_stdout 06:08:01 INFO - Output received: 06:08:01 INFO - Usage: mozinstall [options] installer 06:08:01 INFO - Options: 06:08:01 INFO - -h, --help show this help message and exit 06:08:01 INFO - -d DEST, --destination=DEST 06:08:01 INFO - Directory to install application into. [default: 06:08:01 INFO - "/builds/slave/test"] 06:08:01 INFO - --app=APP Application being installed. [default: firefox] 06:08:01 INFO - mkdir: /builds/slave/test/build/application 06:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:08:23 INFO - Reading from file tmpfile_stdout 06:08:23 INFO - Output received: 06:08:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:08:23 INFO - Running post-action listener: _resource_record_post_action 06:08:23 INFO - [mozharness: 2017-01-25 14:08:23.032976Z] Finished install step (success) 06:08:23 INFO - [mozharness: 2017-01-25 14:08:23.033144Z] Running run-tests step. 06:08:23 INFO - Running pre-action listener: _resource_record_pre_action 06:08:23 INFO - Running main action method: run_tests 06:08:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:23 INFO - 2017-01-25 06:08:23.047 screenresolution[2480:34679] starting screenresolution argv=screenresolution get 06:08:23 INFO - 2017-01-25 06:08:23.050 screenresolution[2480:34679] Display 0: 1600x1200x32@60 06:08:23 INFO - 2017-01-25 06:08:23.056 screenresolution[2481:34681] starting screenresolution argv=screenresolution list 06:08:23 INFO - Available Modes on Display 0 06:08:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:23 INFO - Intel Iris: 06:08:23 INFO - Chipset Model: Intel Iris 06:08:23 INFO - Type: GPU 06:08:23 INFO - Bus: Built-In 06:08:23 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:23 INFO - Vendor: Intel (0x8086) 06:08:23 INFO - Device ID: 0x0a2e 06:08:23 INFO - Revision ID: 0x0009 06:08:23 INFO - Displays: 06:08:23 INFO - Display: 06:08:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:23 INFO - Display Serial Number: 1600x1200 60 06:08:23 INFO - Main Display: Yes 06:08:23 INFO - Mirror: Off 06:08:23 INFO - Online: Yes 06:08:23 INFO - Rotation: Supported 06:08:23 INFO - Return code: 0 06:08:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:23 INFO - Python 2.7.3 06:08:23 INFO - Return code: 0 06:08:23 INFO - grabbing minidump binary from tooltool 06:08: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')]} 06:08:23 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 0x1046ced30>, '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 0x1045f5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046c3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:23 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 06:08:23 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 06:08:23 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 06:08:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:23 INFO - Return code: 0 06:08:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:08:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:08:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 06:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 06:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.65oNHFqSC2/Render', 06:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:23 INFO - 'HOME': '/Users/cltbld', 06:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:23 INFO - 'LOGNAME': 'cltbld', 06:08:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:23 INFO - 'MOZ_NO_REMOTE': '1', 06:08:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:23 INFO - 'NO_EM_RESTART': '1', 06:08:23 INFO - 'PAGER': '/bin/cat', 06:08:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:23 INFO - 'PWD': '/builds/slave/test', 06:08:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:23 INFO - 'SHELL': '/bin/bash', 06:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V2WdmIjwKT/Listeners', 06:08:23 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 06:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:23 INFO - 'USER': 'cltbld', 06:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:23 INFO - 'XPC_FLAGS': '0x0', 06:08:23 INFO - 'XPC_SERVICE_NAME': '0', 06:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 06:08:23 INFO - mozversion application_buildid: 20170125044851 06:08:23 INFO - mozversion application_changeset: c989c7b352279925edf138373e4ca3f1540dbd5f 06:08:23 INFO - mozversion application_display_name: Nightly 06:08:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:23 INFO - mozversion application_name: Firefox 06:08:23 INFO - mozversion application_remotingname: firefox 06:08:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:23 INFO - mozversion application_vendor: Mozilla 06:08:23 INFO - mozversion application_version: 54.0a1 06:08:23 INFO - mozversion platform_buildid: 20170125044851 06:08:23 INFO - mozversion platform_changeset: c989c7b352279925edf138373e4ca3f1540dbd5f 06:08:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:23 INFO - mozversion platform_version: 54.0a1 06:08:23 INFO - using testdate: 1485353303 06:08:23 INFO - actual date: 1485353303 06:08:23 INFO - starting webserver on 'localhost:61051' 06:08:23 INFO - SUITE-START | Running 2 tests 06:08:23 INFO - TEST-START | dromaeo_css 06:08:23 INFO - operating with platform_type : mac_ 06:08:23 INFO - Initialising browser for dromaeo_css test... 06:08:23 INFO - TEST-INFO | started process 2491 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpkC6LMo/profile http://localhost:61051/getInfo.html) 06:08:26 INFO - PROCESS | 2491 | 2017-01-25 06:08:26.259 plugin-container[2493:34843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 06:08:26 INFO - PROCESS | 2491 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:26 INFO - PROCESS | 2491 | 2017-01-25 06:08:26.262 plugin-container[2493:34843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 06:08:26 INFO - PROCESS | 2491 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:26 INFO - PROCESS | 2491 | 2017-01-25 06:08:26.262 plugin-container[2493:34843] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:26 INFO - PROCESS | 2491 | __metrics Screen width/height:1600/1200 06:08:26 INFO - PROCESS | 2491 | colorDepth:24 06:08:26 INFO - PROCESS | 2491 | Browser inner width/height: 1024/689 06:08:26 INFO - PROCESS | 2491 | __metrics 06:08:26 ERROR - PROCESS | 2491 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3279: TypeError: this.tabs is undefined 06:08:27 INFO - TEST-INFO | 2491: exit 0 06:08:27 INFO - Browser initialized. 06:08:27 INFO - Running cycle 1/1 for dromaeo_css test... 06:08:27 INFO - TEST-INFO | started process 2494 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpkC6LMo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:29 INFO - PROCESS | 2494 | 2017-01-25 06:08:29.825 plugin-container[2495:34963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 06:08:29 INFO - PROCESS | 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:29 INFO - PROCESS | 2494 | 2017-01-25 06:08:29.826 plugin-container[2495:34963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 06:08:29 INFO - PROCESS | 2494 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:29 INFO - PROCESS | 2494 | 2017-01-25 06:08:29.826 plugin-container[2495:34963] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:39 INFO - PROCESS | 2494 | RSS: Main: 233066496 06:08:39 INFO - PROCESS | 2494 | 06:11:42 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:61051/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61051/tests/dromaeo/cssquery-ext.html) 06:11:43 INFO - PROCESS | 2494 | RSS: Main: 265289728 06:11:43 INFO - PROCESS | 2494 | 06:13:27 INFO - PROCESS | 2494 | ************************* 06:13:27 INFO - PROCESS | 2494 | A coding exception was thrown and uncaught in a Task. 06:13:27 INFO - PROCESS | 2494 | 06:13:27 ERROR - PROCESS | 2494 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:13:27 INFO - PROCESS | 2494 | Full stack: 06:13:27 INFO - PROCESS | 2494 | ************************* 06:14:46 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:61051/tests/dromaeo/cssquery-ext.html (next: http://localhost:61051/tests/dromaeo/cssquery-jquery.html) 06:14:46 INFO - PROCESS | 2494 | RSS: Main: 265940992 06:14:46 INFO - PROCESS | 2494 | 06:17:48 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:61051/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61051/tests/dromaeo/cssquery-mootools.html) 06:17:49 INFO - PROCESS | 2494 | RSS: Main: 277307392 06:17:49 INFO - PROCESS | 2494 | 06:20:51 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:61051/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61051/tests/dromaeo/cssquery-prototype.html) 06:20:51 INFO - PROCESS | 2494 | RSS: Main: 270852096 06:20:51 INFO - PROCESS | 2494 | 06:23:54 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:61051/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61051/tests/dromaeo/cssquery-yui.html) 06:23:55 INFO - PROCESS | 2494 | RSS: Main: 275931136 06:23:55 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | Cycle 1(1): loaded http://localhost:61051/tests/dromaeo/cssquery-yui.html (next: http://localhost:61051/tests/dromaeo/cssquery-dojo.html) 06:26:58 INFO - PROCESS | 2494 | RSS: Main: 278061056 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | __start_tp_report 06:26:58 INFO - PROCESS | 2494 | _x_x_mozilla_page_load 06:26:58 INFO - PROCESS | 2494 | _x_x_mozilla_page_load_details 06:26:58 INFO - PROCESS | 2494 | |i|pagename|runs| 06:26:58 INFO - PROCESS | 2494 | |0;dojo.html;3141.2775061735815;3198.376316618259;3321.754342121704;3400.1948392576123;3412.9658703412974;5002.624803139765;5683.261176047118;5685.602007859451;5741;5771.192033115367;5681;5769.855753606162;5772.989726797804;5778.104393818954;5789.623674461157;5229.633925625189;5270.156774916014;5350.973245133789;5355.437679043703;5376.489233522819;5364.490373414539;5365.463453654643;5367.597430192732;5368.731563421833;5381.381141168777;5357.4374690657505;5363.034653762321;5368.355797304329;5374.408815030343;5378.946210537903;5321.52106310432;5350.705711185883;5351.036813373591;5352.8661783455145;5374.381946076212;5215.061288967984;5301.151815709506;5302.549283310923;5310.309659744208;5312.92030619541;5747.367789543188;5817.214676018725;5825.796097136622;5868.823935281948;5911.320198177222;5322.733863306819;6110.08348747687;6139.508839292846;6194.442500175006;6203.472704820096;6112.533877023307;6118.5278101325175;6146.524834039843;6164;6201.472874805647;5930.110483427434;5936.1095835625;5941.732622032019;5967.1347654589845;5984.102384642339;6269.435750782452;6275.748970041239;6287.842803929939;6289.779857704958;6294.590851594632;6099.9390006099375;6137.959273351923;6140.04415716754;6181.081539582295;6197.287311959182;6075.362086980892;6184.938150618436;6209.534284928558;6209.627422354673;6220.564560480813;6183.536234782319;6184.474319682878;6185.350538193515;6194.5354098442795;6201.937980620136;6123.846903827351;6137.447629713258;6147.493864003348;6184.536159787944;6191.783287584913;4962.851114466572;4983.127952608279;4997.425296090923;5004.449510553836;5010.749462526859;3679.9696085096216;4446.021875187454;4472.508024117345;4481.686281960297;4485.75328356937;5755.740991655385;5893.587448878623;5913.793017244376;5933.436323549256;5973.581849270597;6108.8581424937165;6121.056693972082;6128.961487065963;6138.846528836725;6204.410580994799;5948.7323070461935;5954.523638108941;5962.986292330314;5968.641881487038;5999.430054144849;5366.221897824794;5386.084365657847;5486.177073439016;5506.752196151183;5517.834464966058;6392.552521323462;6409.134766806422;6410.583312084699;6416.582922110048;6429.54993150475;5402.919416116713;6193.473554747897;6208.317085120723;6217.409346112112;6229.595076320024;5641.379448260606;6168.1056246844855;6188;6215.96892015537;6434.4852411806005;5087.465816089405;5483.232347471437;6174.567780255339;6181.103739957771;6202.875942481215;6136.6011209271255;6167.044108163242;6172.567920245539;6179.227596550431;6188.164597779422;5993.850153746192;6019.307779605227;6064.211652485149;6077.665728384981;6080.878382432238;5796.652200867961;6047.069404886859;6050.334463209131;6192.195014648067;6205.782797602063;5947.375525569926;6034.155218269533;6042.879142417215;6044.365341639064;6047.697615119315;5957.851053723518;6153.446189842935;6157.415045570664;6181.3200547938795;6204.627722336674;5878.764849405976;5995.910061348996;6022.969885150546;6056.606320589148;6057.909131363122 06:26:58 INFO - PROCESS | 2494 | |1;ext.html;444.6309563062657;468.91559519286534;477.5558730380744;508.6723651270184;515.1474310016921;28139.718602813966;42207.57792422079;42212.36681449781;42472.9381765456;44575.77712111435;43736;44213.557864421346;44221.8944526387;44259;44331.55668443315;8430.494170349768;9236.907630923706;9530.666426675069;9543.33196676233;9579.904200958006;8383.62273697682;11243.662690119269;11481.655550333462;11486;11662.950334469897;11536.942315288412;11566.47950842214;11569.710757231094;11592.768144637103;11601.535938562447;11466.2546934449;11507.827382589268;11541.826872596917;11545.307281563091;11550.249233799776;11330.263532870336;11380.829287560693;11382.829257561143;11395.658130256106;11419.885801141965;34849.340819213874;35216.471752923724;41114.38328425076;41452;41700.37449438261;3965.504311961005;4041.5150181978056;4056.1482088761395;4120.484939382577;4126.381042843567;28509.572356414672;29086.563701544495;29122.271943201376;29176.708232917823;29180.854095729384;3147.339058797655;3353.2455197580593;3357.1103657530775;3360.462326027824;3362.899113026613;485504;488021;489685;492576;495664.5216773929;56427;56496.15255771167;56709.43290567124;56884.43115568874;56893.43106568881;59961;60203;60329;60572;60635.69682151561;13477.932610337082;15377.846221537866;15582;15589.610259743597;15610.141442220787;10816.459177041117;11107.166962477846;11955.581554645545;12057.397130143458;12087.939560302142;17835.375761848532;18278.725819112726;18322.63354732898;18702.812971870106;18716.34492792773;6403.935960640427;7396.889046664304;7676.884846727304;7751.37988960893;7786.104597971193;817.1542453560536;824.3899514359331;869.7477731457933;896.6863544906743;900.3832374823136;29231.707682923323;30128;30197.69802301993;30732.53901191484;30732.92434764817;994.7463396833762;1063.4842101580728;1065.648336049102;1108.141190577309;1233.9136260461862;15105.848941510445;15438.45965391206;15746.897717159689;15803.525894223192;15845.841541584667;649187;651560;652579;652940;654935;61540;61618.38381616216;61738.38261617416;61963.38036619666;62238;1412.5479846449243;1440.265464613055;1454.0693955868041;1457.7084583083426;1459.9270036498133;26081.608775868757;28175.43649127047;29167.416651666423;29470.852645736635;29854.25364365908;41931;50633.240501391774;50814;51159.48840511547;51227;524947;526847;529043;529897;530032;25524.872375638002;26547.938082476485;26558.468830623668;26610.467790644467;26617;42238.788806055774;45090.549094508635;45501;47392.05215895732;49741.25388119253;33427.99716008524;35908.820455897556;38308;39043.41434878422;40270.99322516961;31475.685243147276;38329;38647.806760966014;39774;39988.8000560007;37074.97820401098;37131.814340928126;37366.252674945805;37776.24447511089;37863.81068094642;31618.367632647678;33355.83322083374;33694.3261134771;33761;34955.47566786449;24860.12989545357;25021.12426065079;25201.49597008086;25448.618270726325;25611.615825762256 06:26:58 INFO - PROCESS | 2494 | |2;jquery.html;387.1193035843454;387.72084099448386;389.88888166872437;391.79626594171026;423.88940974646414;16258.51224463265;19794.505137371572;20793;21243.256486023012;21298.828564428968;21408.89295553524;21463.463413414633;21466.46333841651;21839.672404913937;22098.22656207033;5875.471207591327;6166.414190651881;6205.596636218631;6218.284897236807;6226.813195604139;6052.878942421149;6101.908471372933;6150.446459818615;6448.871022579546;6734.494912881529;6806.557573757715;6829.146356705412;6834.350736680008;6846.726130954756;6849.554778939353;6596.43930265928;6635.303293154222;6720.966395168018;6721.428678562328;6729.091572637681;5746.626469279525;5930.110483427477;5994.8501287467725;6002.879942401171;6022.879542409171;10158.796824063478;13188.274644894527;19271.51821204467;22023;26028.739712602823;2254.932352029425;2644.8942042318286;2664.5603475426533;2669.9065532706263;2703.6485256916476;10050.722433080808;10166.4916754162;10618.893811061867;10696.78606427867;12428.81926217008;1847.7413162157309;1856.2203874372797;1890.423420856652;1891.3569386408687;1900.1449347793548;125272.74727252702;127660;128555;128678;129606.99999999905;10337.74155646115;11688.18182727218;11930.940345298219;12024;12200.877991220152;14006.929965350311;14644.048136871283;15549.533513994598;15639.296231669603;15641.921790390976;8406;8692.826143477096;8775.210231079234;8871.379003469825;8918.37571369998;8462.280706139923;8491.787705307417;8499;8646.178613031752;8657.696980605648;4778.235482322812;4859.1496488114435;4871.853844384604;4894.507175311493;4940.728259945738;2043.4482689673705;2071.3682326890444;2072.9792702073087;2074.294739788449;2078.4388215181816;377.6167190301846;384.078212290505;1022.9028242316969;1031.2471895516314;1063.9095676867398;11491.597794077166;11823.290602563873;12068.517259309705;12272.325022123869;12370.938145309216;418.1031687031301;420.53740885026224;421.4058177969049;429.9183155200502;441.4327589048058;5454.481824226692;6018.247719035121;6081.6351018939;6115.785947491817;6189.6286222826775;665551.9063401392;739272;746690;760048;765665;158198.41801582067;159736.4026359745;160739.9815004611;161530;162437;368.1789609171509;442.7586965104026;454.8067071494668;462.9189891768927;464.68865859873966;9170.495622740547;9651.662191823252;11120.165987550805;11459.656210313704;11557.133215009077;47585.524144759496;57705.71147144237;57906.84186316335;58627.41372586219;59771;87668.12331876854;88856.22287554083;88993.55503222442;89491;89836;8085.595720213848;8142;8430.957845210734;8531.104234055334;8531.829363412573;12329.550825071345;12332.691682708004;12404.937975310066;12415.193012454161;12947.158434701714;9427;10680.732981675521;10695.572177112828;10866.239363244813;10958.671239862817;10782.838257426301;10888.61889833852;10997.285176463824;11435.256708313933;11470.369129697612;11141.220114592224;11211.047060999907;11349.319040857245;11654.592089276835;11665.766684666429;7581.50720203185;8140.959295203486;8183.567875621861;8348.457350272212;8376.58117094155;8287.834243315221;10698.304610200312;10735.838962415413;11179.552817887194;11262.549498019987 06:26:58 INFO - PROCESS | 2494 | |3;mootools.html;1313.133332000879;1378.2281922123611;1447.9710405791877;1473.697891932154;1489.14374234815;7530.736424225153;7771.727989520368;8114.310283625891;8526.616302266397;8575.09961454047;8048.517088974666;8352;8366.330693544516;8429.325653947684;8455.746327610166;1881.435569329199;2021.413790000898;2065.834733221346;2109.2301310021808;2338.7778161074757;2093.8638988465777;2308.35366097493;2314.4792421705065;2360.232924299601;2370.8458950168183;2099.958000839982;2267.9773202268016;2333.2533589251448;2350.095213342858;2358.174638876397;2280.304507125326;2305.2162264829954;2305.7463678995296;2307.273208939187;2308.4113551044543;2299.9080036798673;2303.9884800576056;2367.5028244068594;2377.037299893546;2377.2511658827416;7476.364509016686;8436.156384361515;8507.48955062692;8510.70212542558;8553.74338769838;5136.948630513684;5276.076686579833;5277.498637629457;5282.075636763551;5285.445028272053;3021.078571035833;3334.9833250833835;3466.843992020365;3488.215151590897;3557.3774589446743;1210.8546974363132;1216.878312168785;1247.6943148928572;1250.1498980693125;1263.564070395712;10688.358698478116;10772.6768196955;10788.784224315548;10797.40614266215;10838.566457341696;4374.868753937387;4669.836555720533;4740.241561350167;4741.193997020515;4751.667383283137;8366.456180348257;9415.387999779992;9494.762630934143;9500.857487137699;9508.334416590773;5207;5256.763445644955;5280.419153893069;5290.047791397508;5315.255864179018;4649.721016739007;4751.287306903992;4797.856064318076;4806.759662016885;4844.709317440965;3806.638369354907;3882.5440459827564;3889.241597888441;3983.46223259862;3987.441758153861;1183.893449589541;1191.1780871198846;1234.0621127942827;1244.520859469111;1287.3691890973355;776.4797585617649;778.3928535742131;802.0856223904754;828.7348048624382;849.0957130655831;5470.671759694431;5524.558035357162;5550.306211723535;5571.749271282802;5616.634918730269;992.4095163377779;1007.9344842585208;1014.5485259059795;1029.809485245232;1053.8155822730992;3741.8129093545213;3768.1521657627363;3769.8115094245727;3847.211321679049;3889.183271512957;9567.995360487188;10055.69832905014;10057.647982320585;10072.546735396925;10096.848547271797;9373.812523749486;9970.850437243447;9998.600055997824;10003.399796012263;10004.449755263386;7591.848163036819;7879.763607091796;7894.447388682852;7897.842043159105;7932.325752311118;5166.3025491558155;5184.118699821115;5285.022270879899;5343.519083282523;5381.8923621526565;8281.37889658266;8923.866142008004;9083.772905677411;9193.494357810385;9270.72187834366;9082.273418126402;9118.544072796201;9289.581968811555;9350.158485736316;9364.578593963424;6982.33667801558;7146.3568278855155;7148.499605027806;7151.2491188424465;7207.243239459991;7703.8074048147;7755.7673269802;7774.533527988338;7809.843803124019;7939.126696063313;6345.555811093179;7090.042844215965;7101.786946391617;7147.428205743632;7238.8914166288605;7015.894761578478;7043.330883566053;7048.259932706992;7131.001659767533;7142.821429464305;6296.338884417253;6315.115883776367;6387.201599800025;6388.776392826228;6399.680015999088;4209.810558524935;4882.2432522959;4887.584555312696;4974.527419895104;5092.516210959953;4927.334809800775;4946.307516947702;5024.57291130258;5075.390953085654;5159.742012899415 06:26:58 INFO - PROCESS | 2494 | |4;prototype.html;357.54234579737937;361.50654356802966;371.72492355656806;374.35050187923963;377.6148328704721;4786.042791441717;4948.430930443;5011.599072074235;5236.057509648262;5452.372977107629;4820;4908.87727806805;5387.78448862045;5415.620906536544;5444.047291723942;3325.7173140283103;3560.8249277738414;4003.459532963042;4045.352743561031;4124.835006599732;3494.9650503495022;3526.7354948378847;4082.9183416331657;4086.89782755432;4095.9180816383655;4064.1871625674835;4081.653059489947;4085.9182816343655;4091.3453847384426;4116.485439320085;3615.7830530168144;3793.506844110248;4037.9999999999854;4053.2298863215897;4092.6725861931272;3064.0960916529675;3508.614052454228;3526.206603514189;3533.964660353415;3543.20277937462;4849.981503884153;4869.561739443467;5301.469853014706;5325.227841962933;5372.919406208871;4555.271156614959;4724.480307166209;4741.170295198326;4742.786574604151;4750.976245118753;4764.094821983812;4938.135826230396;5094.439611642716;5527.751251193706;5692.829215123553;3293.9835300823584;3385.779924304912;3864.7294689371756;3927.9410808837893;3935.1736135411597;4818.180909245401;4862.975685121587;5888.852778680524;5925.259342582177;6084.5132389408745;3234.6252842541826;3829.376876839374;3886.4753258310334;3908.472356231929;3973.6423721864594;3414.685346141753;3941.625545573166;3984.7210695251056;3999.0409494600717;4003.8999025024023;4640.582347588733;4654.604358629555;4656.790444430008;4709.387779588632;4729.408823897013;4829.251466022772;5400.432954539795;5659.547236221092;5758;5762.942370576325;1126.8253420719802;1188.2217147768054;1278.2266728629231;1286.9292188929512;1292.9224246545236;756.8107973006748;817.811903262253;864.7461181287159;878.433410450256;882.955852207387;4419.237976264834;4463.732176069446;4918.0901533216465;5152.381714194321;5161.38063432383;5079.6952182869145;5163.380394352702;5218.843434696965;5525.502125385267;5585.441455854382;4163.354680024601;4323.48118225815;4448.909012763996;5049.141645920203;5106.821261255913;2645.061003343824;2653.4693061387798;2706.9052583159496;2868.4406540724776;2882.6396700412447;4767.666263361601;5325.3875804282225;5800.53595712342;5860.765569377262;5865.35481097079;5456.263404440429;5747.626404283792;5764.855878603068;5811.912821307599;5873.239577398615;5052.928299397303;5103.167741903205;5415.972920135374;5501.449855014466;5814.651120932673;5168.767405466688;5611.979687312646;5625.943740562542;5643.463870932255;5662.66024038559;4798.920242945309;5143.541844682208;5509.834704958857;5536.114221724505;5620.325560932714;5156.355455568054;5520.503154716095;5867.178594996789;5919.467247947706;5959.28488581373;3436.244026314207;3439.329330780474;3831.750936189139;3892;3896.9346246489404;5828.825135245949;5843.597536591103;5863.651360187266;5870.236869207147;5886.58793884424;4794.880127996828;5106.66806657566;5422;5560.749766260447;5689.476865891544;5124.026434977342;5654.434556544313;5694.658320500783;5713.514351280188;5735.082386818089;5066.949330506795;5212.426633070435;5444.50999410055;5640.492355688008;5642.294713160855;5025.743564108972;5072.365954255718;5580.581456390868;5585.748641311069;5656.462636049569;4669.136209801061;5449.618526703092;5614.438556144353;5678.744456499385;5693.544516438592 06:26:58 INFO - PROCESS | 2494 | |5;yui.html;707.6497133918709;732.9450291228159;791.0072858562506;809.4414853750911;827.2678679368756;15725.685486290282;16350.264238109285;16364.018158910474;16386;16410.753838692413;14959.177245251507;15104.622384440394;15118.546443606681;15166.620834479143;15168.317425715843;691.3950293493193;713.0552018575409;759.4683721395032;866.2550206822128;891.5675897189858;873.2795443758893;890.1765866573394;891.0643823984823;893.352319568311;896.7802888292377;950.3300173377752;954.2795189631818;955.9856602150974;956.311455751861;957.8898426680951;907.9092090790901;949.2880339745191;949.3449519831329;949.3686698345592;950.3680052764903;671.6742379945722;711.1395211793736;711.4130842055273;711.8042538301956;712.8289210589473;15902.681946361125;16009;16150;16312.347506099743;16587.502374928772;14754.040987335897;14990.850091499055;15051.322690478955;15298.923505382512;15764.448244311394;11735.119866010084;11988.820167697491;12072.396380180955;12182.147249692525;12532.624021279376;3445.018169821614;3769.4157405602177;3873.690104791638;3997.160596274786;4022.6781857451624;115629;117028;120131;121133.99999999911;121300;11387.430628468543;11545.711357216136;11646.592369267035;11729.824052639216;12059;24257.87871060669;24338;24349.756502435102;24387.634185487233;24434.633480497807;7904.644291006918;8038.477498962548;8057.5165490070785;8073.838523229502;8076.6769329227345;4327.221100201931;4375.343149488395;4405.361222622766;4420.84527041552;4475.70907890986;3625.601183869825;3706.3513885070006;3726.720495962814;3734.8506059757847;3742.4573436851506;589.1604463639297;612.3417326374166;642.4218203616689;652.7519542573887;658.8023592922104;661.6592454885738;671.4796033074309;672.2538430511415;697.8046147078725;719.068805896355;8181.263686268265;8573.657053717781;9080.00119986801;10299.588016479256;10530.526126324334;673.6687082377694;697.6407150317591;705.8517711280585;711.121764620693;736.7053178728444;5012.824551140692;5022.648414610942;5083.847484575469;5107.182850743863;5144.536991670768;82425.58787206025;82463.5876820612;104009;104023.4798826001;104929.47535262427;68699.31300686928;81219;83425.58287208524;83451.5827420859;84298.15701843025;85340.86647833853;85885.57057214799;87864.56067719749;87970.80072998098;88522;9029.142231487998;9524;9596.520173991134;9658.323917326;9709.980452052432;38407.80796096002;49012;49815.25277120918;50522;51278;70283.64858175676;70475.29524704893;72234.55530889248;72543;73498;11375.829362559734;11511.021563167225;11621.6513504595;11640.380914294215;11776.411179441166;12550.623481295575;12622.03105475677;12736.23582585048;13122.278274694601;13183;10483.318584291997;11807.173497855068;11967.461464234304;12189.878101219228;12211.75576488483;11502.94248528752;11853.051755859338;11874.881251187722;12119.636410907688;12307.384630768605;11550.711232219262;11789.711499873347;11945.104117191418;12217;12227.572034978733;9963.601455941682;9986.900130998598;10064.949675251577;10332.586696532055;10522.421266830397;9335.813283734424;9644.855327170239;9735.853962190715;9760.414375137514;9767.85348219763 06:26:58 INFO - PROCESS | 2494 | __end_tp_report 06:26:58 INFO - PROCESS | 2494 | __start_cc_report 06:26:58 INFO - PROCESS | 2494 | _x_x_mozilla_cycle_collect,397 06:26:58 INFO - PROCESS | 2494 | __end_cc_report 06:26:58 INFO - PROCESS | 2494 | __startTimestamp1485354418620__endTimestamp 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | ------- Summary: start ------- 06:26:58 INFO - PROCESS | 2494 | Number of tests: 6 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | [#0] dojo.html Cycles:165 Average:5752.14 Median:5984.10 stddev:634.16 (10.6%) stddev-sans-first:602.12 06:26:58 INFO - PROCESS | 2494 | Values: 3141.3 3198.4 3321.8 3400.2 3413.0 5002.6 5683.3 5685.6 5741.0 5771.2 5681.0 5769.9 5773.0 5778.1 5789.6 5229.6 5270.2 5351.0 5355.4 5376.5 5364.5 5365.5 5367.6 5368.7 5381.4 5357.4 5363.0 5368.4 5374.4 5378.9 5321.5 5350.7 5351.0 5352.9 5374.4 5215.1 5301.2 5302.5 5310.3 5312.9 5747.4 5817.2 5825.8 5868.8 5911.3 5322.7 6110.1 6139.5 6194.4 6203.5 6112.5 6118.5 6146.5 6164.0 6201.5 5930.1 5936.1 5941.7 5967.1 5984.1 6269.4 6275.7 6287.8 6289.8 6294.6 6099.9 6138.0 6140.0 6181.1 6197.3 6075.4 6184.9 6209.5 6209.6 6220.6 6183.5 6184.5 6185.4 6194.5 6201.9 6123.8 6137.4 6147.5 6184.5 6191.8 4962.9 4983.1 4997.4 5004.4 5010.7 3680.0 4446.0 4472.5 4481.7 4485.8 5755.7 5893.6 5913.8 5933.4 5973.6 6108.9 6121.1 6129.0 6138.8 6204.4 5948.7 5954.5 5963.0 5968.6 5999.4 5366.2 5386.1 5486.2 5506.8 5517.8 6392.6 6409.1 6410.6 6416.6 6429.5 5402.9 6193.5 6208.3 6217.4 6229.6 5641.4 6168.1 6188.0 6216.0 6434.5 5087.5 5483.2 6174.6 6181.1 6202.9 6136.6 6167.0 6172.6 6179.2 6188.2 5993.9 6019.3 6064.2 6077.7 6080.9 5796.7 6047.1 6050.3 6192.2 6205.8 5947.4 6034.2 6042.9 6044.4 6047.7 5957.9 6153.4 6157.4 6181.3 6204.6 5878.8 5995.9 6023.0 6056.6 6057.9 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | [#1] ext.html Cycles:180 Average:69080.15 Median:37249.03 stddev:149878.03 (402.4%) stddev-sans-first:150209.89 06:26:58 INFO - PROCESS | 2494 | Values: 444.6 468.9 477.6 508.7 515.1 28139.7 42207.6 42212.4 42472.9 44575.8 43736.0 44213.6 44221.9 44259.0 44331.6 8430.5 9236.9 9530.7 9543.3 9579.9 8383.6 11243.7 11481.7 11486.0 11663.0 11536.9 11566.5 11569.7 11592.8 11601.5 11466.3 11507.8 11541.8 11545.3 11550.2 11330.3 11380.8 11382.8 11395.7 11419.9 34849.3 35216.5 41114.4 41452.0 41700.4 3965.5 4041.5 4056.1 4120.5 4126.4 28509.6 29086.6 29122.3 29176.7 29180.9 3147.3 3353.2 3357.1 3360.5 3362.9 485504.0 488021.0 489685.0 492576.0 495664.5 56427.0 56496.2 56709.4 56884.4 56893.4 59961.0 60203.0 60329.0 60572.0 60635.7 13477.9 15377.8 15582.0 15589.6 15610.1 10816.5 11107.2 11955.6 12057.4 12087.9 17835.4 18278.7 18322.6 18702.8 18716.3 6403.9 7396.9 7676.9 7751.4 7786.1 817.2 824.4 869.7 896.7 900.4 29231.7 30128.0 30197.7 30732.5 30732.9 994.7 1063.5 1065.6 1108.1 1233.9 15105.8 15438.5 15746.9 15803.5 15845.8 649187.0 651560.0 652579.0 652940.0 654935.0 61540.0 61618.4 61738.4 61963.4 62238.0 1412.5 1440.3 1454.1 1457.7 1459.9 26081.6 28175.4 29167.4 29470.9 29854.3 41931.0 50633.2 50814.0 51159.5 51227.0 524947.0 526847.0 529043.0 529897.0 530032.0 25524.9 26547.9 26558.5 26610.5 26617.0 42238.8 45090.5 45501.0 47392.1 49741.3 33428.0 35908.8 38308.0 39043.4 40271.0 31475.7 38329.0 38647.8 39774.0 39988.8 37075.0 37131.8 37366.3 37776.2 37863.8 31618.4 33355.8 33694.3 33761.0 34955.5 24860.1 25021.1 25201.5 25448.6 25611.6 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | [#2] jquery.html Cycles:180 Average:39722.90 Median:380.85 stddev:123020.83 (32301.9%) stddev-sans-first:123330.48 06:26:58 INFO - PROCESS | 2494 | Values: 387.1 387.7 389.9 391.8 423.9 16258.5 19794.5 20793.0 21243.3 21298.8 21408.9 21463.5 21466.5 21839.7 22098.2 5875.5 6166.4 6205.6 6218.3 6226.8 6052.9 6101.9 6150.4 6448.9 6734.5 6806.6 6829.1 6834.4 6846.7 6849.6 6596.4 6635.3 6721.0 6721.4 6729.1 5746.6 5930.1 5994.9 6002.9 6022.9 10158.8 13188.3 19271.5 22023.0 26028.7 2254.9 2644.9 2664.6 2669.9 2703.6 10050.7 10166.5 10618.9 10696.8 12428.8 1847.7 1856.2 1890.4 1891.4 1900.1 125272.7 127660.0 128555.0 128678.0 129607.0 10337.7 11688.2 11930.9 12024.0 12200.9 14006.9 14644.0 15549.5 15639.3 15641.9 8406.0 8692.8 8775.2 8871.4 8918.4 8462.3 8491.8 8499.0 8646.2 8657.7 4778.2 4859.1 4871.9 4894.5 4940.7 2043.4 2071.4 2073.0 2074.3 2078.4 377.6 384.1 1022.9 1031.2 1063.9 11491.6 11823.3 12068.5 12272.3 12370.9 418.1 420.5 421.4 429.9 441.4 5454.5 6018.2 6081.6 6115.8 6189.6 665551.9 739272.0 746690.0 760048.0 765665.0 158198.4 159736.4 160740.0 161530.0 162437.0 368.2 442.8 454.8 462.9 464.7 9170.5 9651.7 11120.2 11459.7 11557.1 47585.5 57705.7 57906.8 58627.4 59771.0 87668.1 88856.2 88993.6 89491.0 89836.0 8085.6 8142.0 8431.0 8531.1 8531.8 12329.6 12332.7 12404.9 12415.2 12947.2 9427.0 10680.7 10695.6 10866.2 10958.7 10782.8 10888.6 10997.3 11435.3 11470.4 11141.2 11211.0 11349.3 11654.6 11665.8 7581.5 8141.0 8183.6 8348.5 8376.6 8287.8 10698.3 10735.8 11179.6 11262.5 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | [#3] mootools.html Cycles:180 Average:5407.84 Median:5163.02 stddev:2937.76 (56.9%) stddev-sans-first:2929.88 06:26:58 INFO - PROCESS | 2494 | Values: 1313.1 1378.2 1448.0 1473.7 1489.1 7530.7 7771.7 8114.3 8526.6 8575.1 8048.5 8352.0 8366.3 8429.3 8455.7 1881.4 2021.4 2065.8 2109.2 2338.8 2093.9 2308.4 2314.5 2360.2 2370.8 2100.0 2268.0 2333.3 2350.1 2358.2 2280.3 2305.2 2305.7 2307.3 2308.4 2299.9 2304.0 2367.5 2377.0 2377.3 7476.4 8436.2 8507.5 8510.7 8553.7 5136.9 5276.1 5277.5 5282.1 5285.4 3021.1 3335.0 3466.8 3488.2 3557.4 1210.9 1216.9 1247.7 1250.1 1263.6 10688.4 10772.7 10788.8 10797.4 10838.6 4374.9 4669.8 4740.2 4741.2 4751.7 8366.5 9415.4 9494.8 9500.9 9508.3 5207.0 5256.8 5280.4 5290.0 5315.3 4649.7 4751.3 4797.9 4806.8 4844.7 3806.6 3882.5 3889.2 3983.5 3987.4 1183.9 1191.2 1234.1 1244.5 1287.4 776.5 778.4 802.1 828.7 849.1 5470.7 5524.6 5550.3 5571.7 5616.6 992.4 1007.9 1014.5 1029.8 1053.8 3741.8 3768.2 3769.8 3847.2 3889.2 9568.0 10055.7 10057.6 10072.5 10096.8 9373.8 9970.9 9998.6 10003.4 10004.4 7591.8 7879.8 7894.4 7897.8 7932.3 5166.3 5184.1 5285.0 5343.5 5381.9 8281.4 8923.9 9083.8 9193.5 9270.7 9082.3 9118.5 9289.6 9350.2 9364.6 6982.3 7146.4 7148.5 7151.2 7207.2 7703.8 7755.8 7774.5 7809.8 7939.1 6345.6 7090.0 7101.8 7147.4 7238.9 7015.9 7043.3 7048.3 7131.0 7142.8 6296.3 6315.1 6387.2 6388.8 6399.7 4209.8 4882.2 4887.6 4974.5 5092.5 4927.3 4946.3 5024.6 5075.4 5159.7 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | [#4] prototype.html Cycles:180 Average:4478.66 Median:4980.02 stddev:1384.84 (27.8%) stddev-sans-first:1353.74 06:26:58 INFO - PROCESS | 2494 | Values: 357.5 361.5 371.7 374.4 377.6 4786.0 4948.4 5011.6 5236.1 5452.4 4820.0 4908.9 5387.8 5415.6 5444.0 3325.7 3560.8 4003.5 4045.4 4124.8 3495.0 3526.7 4082.9 4086.9 4095.9 4064.2 4081.7 4085.9 4091.3 4116.5 3615.8 3793.5 4038.0 4053.2 4092.7 3064.1 3508.6 3526.2 3534.0 3543.2 4850.0 4869.6 5301.5 5325.2 5372.9 4555.3 4724.5 4741.2 4742.8 4751.0 4764.1 4938.1 5094.4 5527.8 5692.8 3294.0 3385.8 3864.7 3927.9 3935.2 4818.2 4863.0 5888.9 5925.3 6084.5 3234.6 3829.4 3886.5 3908.5 3973.6 3414.7 3941.6 3984.7 3999.0 4003.9 4640.6 4654.6 4656.8 4709.4 4729.4 4829.3 5400.4 5659.5 5758.0 5762.9 1126.8 1188.2 1278.2 1286.9 1292.9 756.8 817.8 864.7 878.4 883.0 4419.2 4463.7 4918.1 5152.4 5161.4 5079.7 5163.4 5218.8 5525.5 5585.4 4163.4 4323.5 4448.9 5049.1 5106.8 2645.1 2653.5 2706.9 2868.4 2882.6 4767.7 5325.4 5800.5 5860.8 5865.4 5456.3 5747.6 5764.9 5811.9 5873.2 5052.9 5103.2 5416.0 5501.4 5814.7 5168.8 5612.0 5625.9 5643.5 5662.7 4798.9 5143.5 5509.8 5536.1 5620.3 5156.4 5520.5 5867.2 5919.5 5959.3 3436.2 3439.3 3831.8 3892.0 3896.9 5828.8 5843.6 5863.7 5870.2 5886.6 4794.9 5106.7 5422.0 5560.7 5689.5 5124.0 5654.4 5694.7 5713.5 5735.1 5066.9 5212.4 5444.5 5640.5 5642.3 5025.7 5072.4 5580.6 5585.7 5656.5 4669.1 5449.6 5614.4 5678.7 5693.5 06:26:58 INFO - PROCESS | 2494 | 06:26:58 INFO - PROCESS | 2494 | [#5] yui.html Cycles:180 Average:20644.65 Median:27414.04 stddev:30227.36 (110.3%) stddev-sans-first:30275.09 06:26:58 INFO - PROCESS | 2494 | Values: 707.6 732.9 791.0 809.4 827.3 15725.7 16350.3 16364.0 16386.0 16410.8 14959.2 15104.6 15118.5 15166.6 15168.3 691.4 713.1 759.5 866.3 891.6 873.3 890.2 891.1 893.4 896.8 950.3 954.3 956.0 956.3 957.9 907.9 949.3 949.3 949.4 950.4 671.7 711.1 711.4 711.8 712.8 15902.7 16009.0 16150.0 16312.3 16587.5 14754.0 14990.9 15051.3 15298.9 15764.4 11735.1 11988.8 12072.4 12182.1 12532.6 3445.0 3769.4 3873.7 3997.2 4022.7 115629.0 117028.0 120131.0 121134.0 121300.0 11387.4 11545.7 11646.6 11729.8 12059.0 24257.9 24338.0 24349.8 24387.6 24434.6 7904.6 8038.5 8057.5 8073.8 8076.7 4327.2 4375.3 4405.4 4420.8 4475.7 3625.6 3706.4 3726.7 3734.9 3742.5 589.2 612.3 642.4 652.8 658.8 661.7 671.5 672.3 697.8 719.1 8181.3 8573.7 9080.0 10299.6 10530.5 673.7 697.6 705.9 711.1 736.7 5012.8 5022.6 5083.8 5107.2 5144.5 82425.6 82463.6 104009.0 104023.5 104929.5 68699.3 81219.0 83425.6 83451.6 84298.2 85340.9 85885.6 87864.6 87970.8 88522.0 9029.1 9524.0 9596.5 9658.3 9710.0 38407.8 49012.0 49815.3 50522.0 51278.0 70283.6 70475.3 72234.6 72543.0 73498.0 11375.8 11511.0 11621.7 11640.4 11776.4 12550.6 12622.0 12736.2 13122.3 13183.0 10483.3 11807.2 11967.5 12189.9 12211.8 11502.9 11853.1 11874.9 12119.6 12307.4 11550.7 11789.7 11945.1 12217.0 12227.6 9963.6 9986.9 10064.9 10332.6 10522.4 9335.8 9644.9 9735.9 9760.4 9767.9 06:26:58 INFO - PROCESS | 2494 | -------- Summary: end -------- 06:26:58 INFO - PROCESS | 2494 | 06:26:59 INFO - TEST-INFO | 2494: exit 0 06:26:59 INFO - TEST-OK | dromaeo_css | took 1115658ms 06:26:59 INFO - TEST-START | kraken 06:26:59 INFO - operating with platform_type : mac_ 06:26:59 INFO - Initialising browser for kraken test... 06:26:59 INFO - TEST-INFO | started process 2499 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpQwOE0J/profile http://localhost:61051/getInfo.html) 06:27:02 INFO - PROCESS | 2499 | 2017-01-25 06:27:02.239 plugin-container[2500:36812] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 06:27:02 INFO - PROCESS | 2499 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:02 INFO - PROCESS | 2499 | 2017-01-25 06:27:02.242 plugin-container[2500:36812] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 06:27:02 INFO - PROCESS | 2499 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:02 INFO - PROCESS | 2499 | 2017-01-25 06:27:02.242 plugin-container[2500:36812] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:02 INFO - PROCESS | 2499 | __metrics Screen width/height:1600/1200 06:27:02 INFO - PROCESS | 2499 | colorDepth:24 06:27:02 INFO - PROCESS | 2499 | Browser inner width/height: 1024/689 06:27:02 INFO - PROCESS | 2499 | __metrics 06:27:02 ERROR - PROCESS | 2499 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3279: TypeError: this.tabs is undefined 06:27:03 INFO - TEST-INFO | 2499: exit 0 06:27:03 INFO - Browser initialized. 06:27:03 INFO - Running cycle 1/1 for kraken test... 06:27:03 INFO - TEST-INFO | started process 2501 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpQwOE0J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:05 INFO - PROCESS | 2501 | 2017-01-25 06:27:05.494 plugin-container[2502:36929] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 06:27:05 INFO - PROCESS | 2501 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:05 INFO - PROCESS | 2501 | 2017-01-25 06:27:05.495 plugin-container[2502:36929] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:27:05 INFO - PROCESS | 2501 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:05 INFO - PROCESS | 2501 | 2017-01-25 06:27:05.495 plugin-container[2502:36929] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:15 INFO - PROCESS | 2501 | RSS: Main: 233451520 06:27:15 INFO - PROCESS | 2501 | 06:27:18 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61051/tests/kraken/driver.html?testName=audio-beat-detection) 06:27:19 INFO - PROCESS | 2501 | RSS: Main: 247037952 06:27:19 INFO - PROCESS | 2501 | 06:27:21 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61051/tests/kraken/driver.html?testName=audio-dft) 06:27:22 INFO - PROCESS | 2501 | RSS: Main: 254976000 06:27:22 INFO - PROCESS | 2501 | 06:27:25 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61051/tests/kraken/driver.html?testName=audio-fft) 06:27:26 INFO - PROCESS | 2501 | RSS: Main: 257646592 06:27:26 INFO - PROCESS | 2501 | 06:27:28 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61051/tests/kraken/driver.html?testName=audio-oscillator) 06:27:28 INFO - PROCESS | 2501 | RSS: Main: 258994176 06:27:28 INFO - PROCESS | 2501 | 06:27:30 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61051/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:27:31 INFO - PROCESS | 2501 | RSS: Main: 259194880 06:27:31 INFO - PROCESS | 2501 | 06:27:34 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61051/tests/kraken/driver.html?testName=imaging-darkroom) 06:27:34 INFO - PROCESS | 2501 | RSS: Main: 264908800 06:27:34 INFO - PROCESS | 2501 | 06:27:37 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61051/tests/kraken/driver.html?testName=imaging-desaturate) 06:27:38 INFO - PROCESS | 2501 | RSS: Main: 266326016 06:27:38 INFO - PROCESS | 2501 | 06:27:41 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61051/tests/kraken/driver.html?testName=json-parse-financial) 06:27:41 INFO - PROCESS | 2501 | RSS: Main: 265854976 06:27:41 INFO - PROCESS | 2501 | 06:27:43 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61051/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:27:44 INFO - PROCESS | 2501 | RSS: Main: 265285632 06:27:44 INFO - PROCESS | 2501 | 06:27:46 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:27:46 INFO - PROCESS | 2501 | RSS: Main: 265306112 06:27:46 INFO - PROCESS | 2501 | 06:27:49 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:27:49 INFO - PROCESS | 2501 | RSS: Main: 265154560 06:27:49 INFO - PROCESS | 2501 | 06:27:52 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:27:53 INFO - PROCESS | 2501 | RSS: Main: 265211904 06:27:53 INFO - PROCESS | 2501 | 06:27:56 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:27:57 INFO - PROCESS | 2501 | RSS: Main: 265605120 06:27:57 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | Cycle 1(1): loaded http://localhost:61051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61051/tests/kraken/driver.html?testName=ai-astar) 06:27:58 INFO - PROCESS | 2501 | RSS: Main: 267251712 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | __start_tp_report 06:27:58 INFO - PROCESS | 2501 | _x_x_mozilla_page_load 06:27:58 INFO - PROCESS | 2501 | _x_x_mozilla_page_load_details 06:27:58 INFO - PROCESS | 2501 | |i|pagename|runs| 06:27:58 INFO - PROCESS | 2501 | |0;ai-astar;106;117;118;119;118;117;118;116;117;120 06:27:58 INFO - PROCESS | 2501 | |1;audio-beat-detection;152;117;125;150;117;137;138;113;132;136 06:27:58 INFO - PROCESS | 2501 | |2;audio-dft;202;201;207;223;196;199;199;196;201;192 06:27:58 INFO - PROCESS | 2501 | |3;audio-fft;83;57;60;88;59;57;58;59;73;60 06:27:58 INFO - PROCESS | 2501 | |4;audio-oscillator;89;70;84;72;70;69;80;70;73;81 06:27:58 INFO - PROCESS | 2501 | |5;imaging-gaussian-blur;90;87;89;86;89;85;88;87;88;85 06:27:58 INFO - PROCESS | 2501 | |6;imaging-darkroom;95;96;95;125;146;142;96;96;94;119 06:27:58 INFO - PROCESS | 2501 | |7;imaging-desaturate;104;98;119;144;93;101;93;91;93;97 06:27:58 INFO - PROCESS | 2501 | |8;json-parse-financial;59;59;61;60;63;61;63;62;61;62 06:27:58 INFO - PROCESS | 2501 | |9;json-stringify-tinderbox;85;68;73;79;87;76;72;71;75;77 06:27:58 INFO - PROCESS | 2501 | |10;stanford-crypto-aes;89;88;89;91;87;92;92;88;102;78 06:27:58 INFO - PROCESS | 2501 | |11;stanford-crypto-ccm;143;142;141;138;141;141;141;163;198;166 06:27:58 INFO - PROCESS | 2501 | |12;stanford-crypto-pbkdf2;177;174;176;179;199;227;202;179;229;177 06:27:58 INFO - PROCESS | 2501 | |13;stanford-crypto-sha256-iterative;69;71;69;69;73;67;67;68;66;69 06:27:58 INFO - PROCESS | 2501 | __end_tp_report 06:27:58 INFO - PROCESS | 2501 | __start_cc_report 06:27:58 INFO - PROCESS | 2501 | _x_x_mozilla_cycle_collect,933 06:27:58 INFO - PROCESS | 2501 | __end_cc_report 06:27:58 INFO - PROCESS | 2501 | __startTimestamp1485354478975__endTimestamp 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | ------- Summary: start ------- 06:27:58 INFO - PROCESS | 2501 | Number of tests: 14 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#0] ai-astar Cycles:10 Average:116.60 Median:118.00 stddev:3.89 (3.3%) stddev-sans-first:1.20 06:27:58 INFO - PROCESS | 2501 | Values: 106.0 117.0 118.0 119.0 118.0 117.0 118.0 116.0 117.0 120.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:136.50 stddev:13.58 (9.9%) stddev-sans-first:12.26 06:27:58 INFO - PROCESS | 2501 | Values: 152.0 117.0 125.0 150.0 117.0 137.0 138.0 113.0 132.0 136.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#2] audio-dft Cycles:10 Average:201.60 Median:201.00 stddev:8.54 (4.2%) stddev-sans-first:9.06 06:27:58 INFO - PROCESS | 2501 | Values: 202.0 201.0 207.0 223.0 196.0 199.0 199.0 196.0 201.0 192.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.00 stddev:11.62 (19.4%) stddev-sans-first:10.43 06:27:58 INFO - PROCESS | 2501 | Values: 83.0 57.0 60.0 88.0 59.0 57.0 58.0 59.0 73.0 60.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.50 stddev:7.11 (9.3%) stddev-sans-first:5.72 06:27:58 INFO - PROCESS | 2501 | Values: 89.0 70.0 84.0 72.0 70.0 69.0 80.0 70.0 73.0 81.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.71 (1.9%) stddev-sans-first:1.54 06:27:58 INFO - PROCESS | 2501 | Values: 90.0 87.0 89.0 86.0 89.0 85.0 88.0 87.0 88.0 85.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:95.00 stddev:20.87 (22.0%) stddev-sans-first:21.37 06:27:58 INFO - PROCESS | 2501 | Values: 95.0 96.0 95.0 125.0 146.0 142.0 96.0 96.0 94.0 119.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:93.00 stddev:16.49 (17.7%) stddev-sans-first:17.48 06:27:58 INFO - PROCESS | 2501 | Values: 104.0 98.0 119.0 144.0 93.0 101.0 93.0 91.0 93.0 97.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:61.50 stddev:1.45 (2.4%) stddev-sans-first:1.32 06:27:58 INFO - PROCESS | 2501 | Values: 59.0 59.0 61.0 60.0 63.0 61.0 63.0 62.0 61.0 62.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:76.50 stddev:6.02 (7.9%) stddev-sans-first:5.50 06:27:58 INFO - PROCESS | 2501 | Values: 85.0 68.0 73.0 79.0 87.0 76.0 72.0 71.0 75.0 77.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:89.00 stddev:5.91 (6.6%) stddev-sans-first:6.26 06:27:58 INFO - PROCESS | 2501 | Values: 89.0 88.0 89.0 91.0 87.0 92.0 92.0 88.0 102.0 78.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:142.50 stddev:19.12 (13.4%) stddev-sans-first:20.04 06:27:58 INFO - PROCESS | 2501 | Values: 143.0 142.0 141.0 138.0 141.0 141.0 141.0 163.0 198.0 166.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.90 Median:189.00 stddev:21.37 (11.3%) stddev-sans-first:21.98 06:27:58 INFO - PROCESS | 2501 | Values: 177.0 174.0 176.0 179.0 199.0 227.0 202.0 179.0 229.0 177.0 06:27:58 INFO - PROCESS | 2501 | 06:27:58 INFO - PROCESS | 2501 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:2.04 (3.0%) stddev-sans-first:2.17 06:27:58 INFO - PROCESS | 2501 | Values: 69.0 71.0 69.0 69.0 73.0 67.0 67.0 68.0 66.0 69.0 06:27:58 INFO - PROCESS | 2501 | -------- Summary: end -------- 06:27:58 INFO - PROCESS | 2501 | 06:28:00 INFO - TEST-INFO | 2501: exit 0 06:28:00 INFO - TEST-OK | kraken | took 60767ms 06:28:00 INFO - SUITE-END | took 1176s 06:28:00 INFO - Completed test suite (00:19:36) 06:28:00 INFO - javascript benchmark 06:28:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7189.116568435623, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.2775061735815, 3198.376316618259, 3321.754342121704, 3400.1948392576123, 3412.9658703412974, 5002.624803139765, 5683.261176047118, 5685.602007859451, 5741.0, 5771.192033115367, 5681.0, 5769.855753606162, 5772.989726797804, 5778.104393818954, 5789.623674461157, 5229.633925625189, 5270.156774916014, 5350.973245133789, 5355.437679043703, 5376.489233522819, 5364.490373414539, 5365.463453654643, 5367.597430192732, 5368.731563421833, 5381.381141168777, 5357.4374690657505, 5363.034653762321, 5368.355797304329, 5374.408815030343, 5378.946210537903, 5321.52106310432, 5350.705711185883, 5351.036813373591, 5352.8661783455145, 5374.381946076212, 5215.061288967984, 5301.151815709506, 5302.549283310923, 5310.309659744208, 5312.92030619541, 5747.367789543188, 5817.214676018725, 5825.796097136622, 5868.823935281948, 5911.320198177222, 5322.733863306819, 6110.08348747687, 6139.508839292846, 6194.442500175006, 6203.472704820096, 6112.533877023307, 6118.5278101325175, 6146.524834039843, 6164.0, 6201.472874805647, 5930.110483427434, 5936.1095835625, 5941.732622032019, 5967.1347654589845, 5984.102384642339, 6269.435750782452, 6275.748970041239, 6287.842803929939, 6289.779857704958, 6294.590851594632, 6099.9390006099375, 6137.959273351923, 6140.04415716754, 6181.081539582295, 6197.287311959182, 6075.362086980892, 6184.938150618436, 6209.534284928558, 6209.627422354673, 6220.564560480813, 6183.536234782319, 6184.474319682878, 6185.350538193515, 6194.5354098442795, 6201.937980620136, 6123.846903827351, 6137.447629713258, 6147.493864003348, 6184.536159787944, 6191.783287584913, 4962.851114466572, 4983.127952608279, 4997.425296090923, 5004.449510553836, 5010.749462526859, 3679.9696085096216, 4446.021875187454, 4472.508024117345, 4481.686281960297, 4485.75328356937, 5755.740991655385, 5893.587448878623, 5913.793017244376, 5933.436323549256, 5973.581849270597, 6108.8581424937165, 6121.056693972082, 6128.961487065963, 6138.846528836725, 6204.410580994799, 5948.7323070461935, 5954.523638108941, 5962.986292330314, 5968.641881487038, 5999.430054144849, 5366.221897824794, 5386.084365657847, 5486.177073439016, 5506.752196151183, 5517.834464966058, 6392.552521323462, 6409.134766806422, 6410.583312084699, 6416.582922110048, 6429.54993150475, 5402.919416116713, 6193.473554747897, 6208.317085120723, 6217.409346112112, 6229.595076320024, 5641.379448260606, 6168.1056246844855, 6188.0, 6215.96892015537, 6434.4852411806005, 5087.465816089405, 5483.232347471437, 6174.567780255339, 6181.103739957771, 6202.875942481215, 6136.6011209271255, 6167.044108163242, 6172.567920245539, 6179.227596550431, 6188.164597779422, 5993.850153746192, 6019.307779605227, 6064.211652485149, 6077.665728384981, 6080.878382432238, 5796.652200867961, 6047.069404886859, 6050.334463209131, 6192.195014648067, 6205.782797602063, 5947.375525569926, 6034.155218269533, 6042.879142417215, 6044.365341639064, 6047.697615119315, 5957.851053723518, 6153.446189842935, 6157.415045570664, 6181.3200547938795, 6204.627722336674, 5878.764849405976, 5995.910061348996, 6022.969885150546, 6056.606320589148, 6057.909131363122], "value": 5711.726210985129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.6309563062657, 468.91559519286534, 477.5558730380744, 508.6723651270184, 515.1474310016921, 28139.718602813966, 42207.57792422079, 42212.36681449781, 42472.9381765456, 44575.77712111435, 43736.0, 44213.557864421346, 44221.8944526387, 44259.0, 44331.55668443315, 8430.494170349768, 9236.907630923706, 9530.666426675069, 9543.33196676233, 9579.904200958006, 8383.62273697682, 11243.662690119269, 11481.655550333462, 11486.0, 11662.950334469897, 11536.942315288412, 11566.47950842214, 11569.710757231094, 11592.768144637103, 11601.535938562447, 11466.2546934449, 11507.827382589268, 11541.826872596917, 11545.307281563091, 11550.249233799776, 11330.263532870336, 11380.829287560693, 11382.829257561143, 11395.658130256106, 11419.885801141965, 34849.340819213874, 35216.471752923724, 41114.38328425076, 41452.0, 41700.37449438261, 3965.504311961005, 4041.5150181978056, 4056.1482088761395, 4120.484939382577, 4126.381042843567, 28509.572356414672, 29086.563701544495, 29122.271943201376, 29176.708232917823, 29180.854095729384, 3147.339058797655, 3353.2455197580593, 3357.1103657530775, 3360.462326027824, 3362.899113026613, 485504.0, 488021.0, 489685.0, 492576.0, 495664.5216773929, 56427.0, 56496.15255771167, 56709.43290567124, 56884.43115568874, 56893.43106568881, 59961.0, 60203.0, 60329.0, 60572.0, 60635.69682151561, 13477.932610337082, 15377.846221537866, 15582.0, 15589.610259743597, 15610.141442220787, 10816.459177041117, 11107.166962477846, 11955.581554645545, 12057.397130143458, 12087.939560302142, 17835.375761848532, 18278.725819112726, 18322.63354732898, 18702.812971870106, 18716.34492792773, 6403.935960640427, 7396.889046664304, 7676.884846727304, 7751.37988960893, 7786.104597971193, 817.1542453560536, 824.3899514359331, 869.7477731457933, 896.6863544906743, 900.3832374823136, 29231.707682923323, 30128.0, 30197.69802301993, 30732.53901191484, 30732.92434764817, 994.7463396833762, 1063.4842101580728, 1065.648336049102, 1108.141190577309, 1233.9136260461862, 15105.848941510445, 15438.45965391206, 15746.897717159689, 15803.525894223192, 15845.841541584667, 649187.0, 651560.0, 652579.0, 652940.0, 654935.0, 61540.0, 61618.38381616216, 61738.38261617416, 61963.38036619666, 62238.0, 1412.5479846449243, 1440.265464613055, 1454.0693955868041, 1457.7084583083426, 1459.9270036498133, 26081.608775868757, 28175.43649127047, 29167.416651666423, 29470.852645736635, 29854.25364365908, 41931.0, 50633.240501391774, 50814.0, 51159.48840511547, 51227.0, 524947.0, 526847.0, 529043.0, 529897.0, 530032.0, 25524.872375638002, 26547.938082476485, 26558.468830623668, 26610.467790644467, 26617.0, 42238.788806055774, 45090.549094508635, 45501.0, 47392.05215895732, 49741.25388119253, 33427.99716008524, 35908.820455897556, 38308.0, 39043.41434878422, 40270.99322516961, 31475.685243147276, 38329.0, 38647.806760966014, 39774.0, 39988.8000560007, 37074.97820401098, 37131.814340928126, 37366.252674945805, 37776.24447511089, 37863.81068094642, 31618.367632647678, 33355.83322083374, 33694.3261134771, 33761.0, 34955.47566786449, 24860.12989545357, 25021.12426065079, 25201.49597008086, 25448.618270726325, 25611.615825762256], "value": 20099.006233371038, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.1193035843454, 387.72084099448386, 389.88888166872437, 391.79626594171026, 423.88940974646414, 16258.51224463265, 19794.505137371572, 20793.0, 21243.256486023012, 21298.828564428968, 21408.89295553524, 21463.463413414633, 21466.46333841651, 21839.672404913937, 22098.22656207033, 5875.471207591327, 6166.414190651881, 6205.596636218631, 6218.284897236807, 6226.813195604139, 6052.878942421149, 6101.908471372933, 6150.446459818615, 6448.871022579546, 6734.494912881529, 6806.557573757715, 6829.146356705412, 6834.350736680008, 6846.726130954756, 6849.554778939353, 6596.43930265928, 6635.303293154222, 6720.966395168018, 6721.428678562328, 6729.091572637681, 5746.626469279525, 5930.110483427477, 5994.8501287467725, 6002.879942401171, 6022.879542409171, 10158.796824063478, 13188.274644894527, 19271.51821204467, 22023.0, 26028.739712602823, 2254.932352029425, 2644.8942042318286, 2664.5603475426533, 2669.9065532706263, 2703.6485256916476, 10050.722433080808, 10166.4916754162, 10618.893811061867, 10696.78606427867, 12428.81926217008, 1847.7413162157309, 1856.2203874372797, 1890.423420856652, 1891.3569386408687, 1900.1449347793548, 125272.74727252702, 127660.0, 128555.0, 128678.0, 129606.99999999905, 10337.74155646115, 11688.18182727218, 11930.940345298219, 12024.0, 12200.877991220152, 14006.929965350311, 14644.048136871283, 15549.533513994598, 15639.296231669603, 15641.921790390976, 8406.0, 8692.826143477096, 8775.210231079234, 8871.379003469825, 8918.37571369998, 8462.280706139923, 8491.787705307417, 8499.0, 8646.178613031752, 8657.696980605648, 4778.235482322812, 4859.1496488114435, 4871.853844384604, 4894.507175311493, 4940.728259945738, 2043.4482689673705, 2071.3682326890444, 2072.9792702073087, 2074.294739788449, 2078.4388215181816, 377.6167190301846, 384.078212290505, 1022.9028242316969, 1031.2471895516314, 1063.9095676867398, 11491.597794077166, 11823.290602563873, 12068.517259309705, 12272.325022123869, 12370.938145309216, 418.1031687031301, 420.53740885026224, 421.4058177969049, 429.9183155200502, 441.4327589048058, 5454.481824226692, 6018.247719035121, 6081.6351018939, 6115.785947491817, 6189.6286222826775, 665551.9063401392, 739272.0, 746690.0, 760048.0, 765665.0, 158198.41801582067, 159736.4026359745, 160739.9815004611, 161530.0, 162437.0, 368.1789609171509, 442.7586965104026, 454.8067071494668, 462.9189891768927, 464.68865859873966, 9170.495622740547, 9651.662191823252, 11120.165987550805, 11459.656210313704, 11557.133215009077, 47585.524144759496, 57705.71147144237, 57906.84186316335, 58627.41372586219, 59771.0, 87668.12331876854, 88856.22287554083, 88993.55503222442, 89491.0, 89836.0, 8085.595720213848, 8142.0, 8430.957845210734, 8531.104234055334, 8531.829363412573, 12329.550825071345, 12332.691682708004, 12404.937975310066, 12415.193012454161, 12947.158434701714, 9427.0, 10680.732981675521, 10695.572177112828, 10866.239363244813, 10958.671239862817, 10782.838257426301, 10888.61889833852, 10997.285176463824, 11435.256708313933, 11470.369129697612, 11141.220114592224, 11211.047060999907, 11349.319040857245, 11654.592089276835, 11665.766684666429, 7581.50720203185, 8140.959295203486, 8183.567875621861, 8348.457350272212, 8376.58117094155, 8287.834243315221, 10698.304610200312, 10735.838962415413, 11179.552817887194, 11262.549498019987], "value": 8996.851731173541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.133332000879, 1378.2281922123611, 1447.9710405791877, 1473.697891932154, 1489.14374234815, 7530.736424225153, 7771.727989520368, 8114.310283625891, 8526.616302266397, 8575.09961454047, 8048.517088974666, 8352.0, 8366.330693544516, 8429.325653947684, 8455.746327610166, 1881.435569329199, 2021.413790000898, 2065.834733221346, 2109.2301310021808, 2338.7778161074757, 2093.8638988465777, 2308.35366097493, 2314.4792421705065, 2360.232924299601, 2370.8458950168183, 2099.958000839982, 2267.9773202268016, 2333.2533589251448, 2350.095213342858, 2358.174638876397, 2280.304507125326, 2305.2162264829954, 2305.7463678995296, 2307.273208939187, 2308.4113551044543, 2299.9080036798673, 2303.9884800576056, 2367.5028244068594, 2377.037299893546, 2377.2511658827416, 7476.364509016686, 8436.156384361515, 8507.48955062692, 8510.70212542558, 8553.74338769838, 5136.948630513684, 5276.076686579833, 5277.498637629457, 5282.075636763551, 5285.445028272053, 3021.078571035833, 3334.9833250833835, 3466.843992020365, 3488.215151590897, 3557.3774589446743, 1210.8546974363132, 1216.878312168785, 1247.6943148928572, 1250.1498980693125, 1263.564070395712, 10688.358698478116, 10772.6768196955, 10788.784224315548, 10797.40614266215, 10838.566457341696, 4374.868753937387, 4669.836555720533, 4740.241561350167, 4741.193997020515, 4751.667383283137, 8366.456180348257, 9415.387999779992, 9494.762630934143, 9500.857487137699, 9508.334416590773, 5207.0, 5256.763445644955, 5280.419153893069, 5290.047791397508, 5315.255864179018, 4649.721016739007, 4751.287306903992, 4797.856064318076, 4806.759662016885, 4844.709317440965, 3806.638369354907, 3882.5440459827564, 3889.241597888441, 3983.46223259862, 3987.441758153861, 1183.893449589541, 1191.1780871198846, 1234.0621127942827, 1244.520859469111, 1287.3691890973355, 776.4797585617649, 778.3928535742131, 802.0856223904754, 828.7348048624382, 849.0957130655831, 5470.671759694431, 5524.558035357162, 5550.306211723535, 5571.749271282802, 5616.634918730269, 992.4095163377779, 1007.9344842585208, 1014.5485259059795, 1029.809485245232, 1053.8155822730992, 3741.8129093545213, 3768.1521657627363, 3769.8115094245727, 3847.211321679049, 3889.183271512957, 9567.995360487188, 10055.69832905014, 10057.647982320585, 10072.546735396925, 10096.848547271797, 9373.812523749486, 9970.850437243447, 9998.600055997824, 10003.399796012263, 10004.449755263386, 7591.848163036819, 7879.763607091796, 7894.447388682852, 7897.842043159105, 7932.325752311118, 5166.3025491558155, 5184.118699821115, 5285.022270879899, 5343.519083282523, 5381.8923621526565, 8281.37889658266, 8923.866142008004, 9083.772905677411, 9193.494357810385, 9270.72187834366, 9082.273418126402, 9118.544072796201, 9289.581968811555, 9350.158485736316, 9364.578593963424, 6982.33667801558, 7146.3568278855155, 7148.499605027806, 7151.2491188424465, 7207.243239459991, 7703.8074048147, 7755.7673269802, 7774.533527988338, 7809.843803124019, 7939.126696063313, 6345.555811093179, 7090.042844215965, 7101.786946391617, 7147.428205743632, 7238.8914166288605, 7015.894761578478, 7043.330883566053, 7048.259932706992, 7131.001659767533, 7142.821429464305, 6296.338884417253, 6315.115883776367, 6387.201599800025, 6388.776392826228, 6399.680015999088, 4209.810558524935, 4882.2432522959, 4887.584555312696, 4974.527419895104, 5092.516210959953, 4927.334809800775, 4946.307516947702, 5024.57291130258, 5075.390953085654, 5159.742012899415], "value": 4396.665368912462, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.54234579737937, 361.50654356802966, 371.72492355656806, 374.35050187923963, 377.6148328704721, 4786.042791441717, 4948.430930443, 5011.599072074235, 5236.057509648262, 5452.372977107629, 4820.0, 4908.87727806805, 5387.78448862045, 5415.620906536544, 5444.047291723942, 3325.7173140283103, 3560.8249277738414, 4003.459532963042, 4045.352743561031, 4124.835006599732, 3494.9650503495022, 3526.7354948378847, 4082.9183416331657, 4086.89782755432, 4095.9180816383655, 4064.1871625674835, 4081.653059489947, 4085.9182816343655, 4091.3453847384426, 4116.485439320085, 3615.7830530168144, 3793.506844110248, 4037.9999999999854, 4053.2298863215897, 4092.6725861931272, 3064.0960916529675, 3508.614052454228, 3526.206603514189, 3533.964660353415, 3543.20277937462, 4849.981503884153, 4869.561739443467, 5301.469853014706, 5325.227841962933, 5372.919406208871, 4555.271156614959, 4724.480307166209, 4741.170295198326, 4742.786574604151, 4750.976245118753, 4764.094821983812, 4938.135826230396, 5094.439611642716, 5527.751251193706, 5692.829215123553, 3293.9835300823584, 3385.779924304912, 3864.7294689371756, 3927.9410808837893, 3935.1736135411597, 4818.180909245401, 4862.975685121587, 5888.852778680524, 5925.259342582177, 6084.5132389408745, 3234.6252842541826, 3829.376876839374, 3886.4753258310334, 3908.472356231929, 3973.6423721864594, 3414.685346141753, 3941.625545573166, 3984.7210695251056, 3999.0409494600717, 4003.8999025024023, 4640.582347588733, 4654.604358629555, 4656.790444430008, 4709.387779588632, 4729.408823897013, 4829.251466022772, 5400.432954539795, 5659.547236221092, 5758.0, 5762.942370576325, 1126.8253420719802, 1188.2217147768054, 1278.2266728629231, 1286.9292188929512, 1292.9224246545236, 756.8107973006748, 817.811903262253, 864.7461181287159, 878.433410450256, 882.955852207387, 4419.237976264834, 4463.732176069446, 4918.0901533216465, 5152.381714194321, 5161.38063432383, 5079.6952182869145, 5163.380394352702, 5218.843434696965, 5525.502125385267, 5585.441455854382, 4163.354680024601, 4323.48118225815, 4448.909012763996, 5049.141645920203, 5106.821261255913, 2645.061003343824, 2653.4693061387798, 2706.9052583159496, 2868.4406540724776, 2882.6396700412447, 4767.666263361601, 5325.3875804282225, 5800.53595712342, 5860.765569377262, 5865.35481097079, 5456.263404440429, 5747.626404283792, 5764.855878603068, 5811.912821307599, 5873.239577398615, 5052.928299397303, 5103.167741903205, 5415.972920135374, 5501.449855014466, 5814.651120932673, 5168.767405466688, 5611.979687312646, 5625.943740562542, 5643.463870932255, 5662.66024038559, 4798.920242945309, 5143.541844682208, 5509.834704958857, 5536.114221724505, 5620.325560932714, 5156.355455568054, 5520.503154716095, 5867.178594996789, 5919.467247947706, 5959.28488581373, 3436.244026314207, 3439.329330780474, 3831.750936189139, 3892.0, 3896.9346246489404, 5828.825135245949, 5843.597536591103, 5863.651360187266, 5870.236869207147, 5886.58793884424, 4794.880127996828, 5106.66806657566, 5422.0, 5560.749766260447, 5689.476865891544, 5124.026434977342, 5654.434556544313, 5694.658320500783, 5713.514351280188, 5735.082386818089, 5066.949330506795, 5212.426633070435, 5444.50999410055, 5640.492355688008, 5642.294713160855, 5025.743564108972, 5072.365954255718, 5580.581456390868, 5585.748641311069, 5656.462636049569, 4669.136209801061, 5449.618526703092, 5614.438556144353, 5678.744456499385, 5693.544516438592], "value": 4049.9759761623286, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.6497133918709, 732.9450291228159, 791.0072858562506, 809.4414853750911, 827.2678679368756, 15725.685486290282, 16350.264238109285, 16364.018158910474, 16386.0, 16410.753838692413, 14959.177245251507, 15104.622384440394, 15118.546443606681, 15166.620834479143, 15168.317425715843, 691.3950293493193, 713.0552018575409, 759.4683721395032, 866.2550206822128, 891.5675897189858, 873.2795443758893, 890.1765866573394, 891.0643823984823, 893.352319568311, 896.7802888292377, 950.3300173377752, 954.2795189631818, 955.9856602150974, 956.311455751861, 957.8898426680951, 907.9092090790901, 949.2880339745191, 949.3449519831329, 949.3686698345592, 950.3680052764903, 671.6742379945722, 711.1395211793736, 711.4130842055273, 711.8042538301956, 712.8289210589473, 15902.681946361125, 16009.0, 16150.0, 16312.347506099743, 16587.502374928772, 14754.040987335897, 14990.850091499055, 15051.322690478955, 15298.923505382512, 15764.448244311394, 11735.119866010084, 11988.820167697491, 12072.396380180955, 12182.147249692525, 12532.624021279376, 3445.018169821614, 3769.4157405602177, 3873.690104791638, 3997.160596274786, 4022.6781857451624, 115629.0, 117028.0, 120131.0, 121133.99999999911, 121300.0, 11387.430628468543, 11545.711357216136, 11646.592369267035, 11729.824052639216, 12059.0, 24257.87871060669, 24338.0, 24349.756502435102, 24387.634185487233, 24434.633480497807, 7904.644291006918, 8038.477498962548, 8057.5165490070785, 8073.838523229502, 8076.6769329227345, 4327.221100201931, 4375.343149488395, 4405.361222622766, 4420.84527041552, 4475.70907890986, 3625.601183869825, 3706.3513885070006, 3726.720495962814, 3734.8506059757847, 3742.4573436851506, 589.1604463639297, 612.3417326374166, 642.4218203616689, 652.7519542573887, 658.8023592922104, 661.6592454885738, 671.4796033074309, 672.2538430511415, 697.8046147078725, 719.068805896355, 8181.263686268265, 8573.657053717781, 9080.00119986801, 10299.588016479256, 10530.526126324334, 673.6687082377694, 697.6407150317591, 705.8517711280585, 711.121764620693, 736.7053178728444, 5012.824551140692, 5022.648414610942, 5083.847484575469, 5107.182850743863, 5144.536991670768, 82425.58787206025, 82463.5876820612, 104009.0, 104023.4798826001, 104929.47535262427, 68699.31300686928, 81219.0, 83425.58287208524, 83451.5827420859, 84298.15701843025, 85340.86647833853, 85885.57057214799, 87864.56067719749, 87970.80072998098, 88522.0, 9029.142231487998, 9524.0, 9596.520173991134, 9658.323917326, 9709.980452052432, 38407.80796096002, 49012.0, 49815.25277120918, 50522.0, 51278.0, 70283.64858175676, 70475.29524704893, 72234.55530889248, 72543.0, 73498.0, 11375.829362559734, 11511.021563167225, 11621.6513504595, 11640.380914294215, 11776.411179441166, 12550.623481295575, 12622.03105475677, 12736.23582585048, 13122.278274694601, 13183.0, 10483.318584291997, 11807.173497855068, 11967.461464234304, 12189.878101219228, 12211.75576488483, 11502.94248528752, 11853.051755859338, 11874.881251187722, 12119.636410907688, 12307.384630768605, 11550.711232219262, 11789.711499873347, 11945.104117191418, 12217.0, 12227.572034978733, 9963.601455941682, 9986.900130998598, 10064.949675251577, 10332.586696532055, 10522.421266830397, 9335.813283734424, 9644.855327170239, 9735.853962190715, 9760.414375137514, 9767.85348219763], "value": 7505.8079604395025, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 119.0, 118.0, 117.0, 118.0, 116.0, 117.0, 120.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 125.0, 150.0, 117.0, 137.0, 138.0, 113.0, 132.0, 136.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 201.0, 207.0, 223.0, 196.0, 199.0, 199.0, 196.0, 201.0, 192.0], "value": 201.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 60.0, 88.0, 59.0, 57.0, 58.0, 59.0, 73.0, 60.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 84.0, 72.0, 70.0, 69.0, 80.0, 70.0, 73.0, 81.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 89.0, 85.0, 88.0, 87.0, 88.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 125.0, 146.0, 142.0, 96.0, 96.0, 94.0, 119.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 98.0, 119.0, 144.0, 93.0, 101.0, 93.0, 91.0, 93.0, 97.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 61.0, 60.0, 63.0, 61.0, 63.0, 62.0, 61.0, 62.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 73.0, 79.0, 87.0, 76.0, 72.0, 71.0, 75.0, 77.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 91.0, 87.0, 92.0, 92.0, 88.0, 102.0, 78.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 141.0, 138.0, 141.0, 141.0, 141.0, 163.0, 198.0, 166.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 174.0, 176.0, 179.0, 199.0, 227.0, 202.0, 179.0, 229.0, 177.0], "value": 191.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 69.0, 73.0, 67.0, 67.0, 68.0, 66.0, 69.0], "value": 68.8, "unit": "score"}]}]} 06:28:00 INFO - Return code: 0 06:28:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:28:00 ERROR - # TBPL SUCCESS # 06:28:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:28:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:28:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:28:00 INFO - 2017-01-25 06:28:00.317 screenresolution[2506:37312] starting screenresolution argv=screenresolution get 06:28:00 INFO - 2017-01-25 06:28:00.320 screenresolution[2506:37312] Display 0: 1600x1200x32@60 06:28:00 INFO - 2017-01-25 06:28:00.326 screenresolution[2507:37315] starting screenresolution argv=screenresolution list 06:28:00 INFO - Available Modes on Display 0 06:28:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:28:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:28:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:28:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:28:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:28:00 INFO - Intel Iris: 06:28:00 INFO - Chipset Model: Intel Iris 06:28:00 INFO - Type: GPU 06:28:00 INFO - Bus: Built-In 06:28:00 INFO - VRAM (Dynamic, Max): 1536 MB 06:28:00 INFO - Vendor: Intel (0x8086) 06:28:00 INFO - Device ID: 0x0a2e 06:28:00 INFO - Revision ID: 0x0009 06:28:00 INFO - Displays: 06:28:00 INFO - Display: 06:28:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:28:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:28:00 INFO - Display Serial Number: 1600x1200 60 06:28:00 INFO - Main Display: Yes 06:28:00 INFO - Mirror: Off 06:28:00 INFO - Online: Yes 06:28:00 INFO - Rotation: Supported 06:28:00 INFO - Return code: 0 06:28:00 INFO - Running post-action listener: _resource_record_post_action 06:28:00 INFO - [mozharness: 2017-01-25 14:28:00.568935Z] Finished run-tests step (success) 06:28:00 INFO - Running post-run listener: _resource_record_post_run 06:28:00 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 262144; Write bytes: 554262016; Read time: 4; Write time: 1715 06:28:00 INFO - TinderboxPrint: CPU usage
54.9% 06:28:00 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 4 06:28:00 INFO - TinderboxPrint: I/O write bytes / time
554,262,016 / 1,715 06:28:00 INFO - TinderboxPrint: CPU idle
2,164.2 (45.1%) 06:28:00 INFO - TinderboxPrint: CPU system
974.4 (20.3%) 06:28:00 INFO - TinderboxPrint: CPU user
1,659.2 (34.6%) 06:28:00 INFO - TinderboxPrint: Swap in / out
250,802,176 / 0 06:28:00 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 0; Write bytes: 254420992; Read time: 0; Write time: 482 06:28:00 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 225280; Write bytes: 298329600; Read time: 3; Write time: 1227 06:28:01 INFO - Running post-run listener: _upload_blobber_files 06:28:01 INFO - Blob upload gear active. 06:28:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:28:01 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 06:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:28:01 INFO - (blobuploader) - INFO - Open directory for files ... 06:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:28:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:02 INFO - (blobuploader) - INFO - Done attempting. 06:28:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:28:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:28:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:03 INFO - (blobuploader) - INFO - Done attempting. 06:28:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:28:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:28:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:04 INFO - (blobuploader) - INFO - Done attempting. 06:28:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:28:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:28:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:06 INFO - (blobuploader) - INFO - Done attempting. 06:28:06 INFO - (blobuploader) - INFO - Iteration through files over. 06:28:06 INFO - Return code: 0 06:28:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:28:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:28:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51aa62216728d03edd2056b3e7e05a2b4e34992d094fada82dff5679c8efcc2f603da4317514d955fde5212c14943501af2821e9d3cab60fe90cedcb09172bbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/581e38fee656b3dca6f79260347314f34037797dd63d3053bf000aa7879fb578d60a4ab7fe0fb19ccc4b00050ff09ae5b60d26e8fc66fe277b95f4a7bc49dae3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ed8e33a3d0b4fe8e5da618a4162acf0c997b63795b2aaff619d5b1922a425c1803ff5cb3b4f52c74ddc44f0bd521ad70672fa270cd5347eca520ce09220e3b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d6995ed64b611015152d0bddbcc4841434e6864f9dafa87ef999d0d776d4d0b8948685318774a55d502bc70febc30a0e6d55fdad0761ebe7d2060ebb3e83d8c"} 06:28:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:28:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:28:06 INFO - Contents: 06:28:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51aa62216728d03edd2056b3e7e05a2b4e34992d094fada82dff5679c8efcc2f603da4317514d955fde5212c14943501af2821e9d3cab60fe90cedcb09172bbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/581e38fee656b3dca6f79260347314f34037797dd63d3053bf000aa7879fb578d60a4ab7fe0fb19ccc4b00050ff09ae5b60d26e8fc66fe277b95f4a7bc49dae3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ed8e33a3d0b4fe8e5da618a4162acf0c997b63795b2aaff619d5b1922a425c1803ff5cb3b4f52c74ddc44f0bd521ad70672fa270cd5347eca520ce09220e3b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d6995ed64b611015152d0bddbcc4841434e6864f9dafa87ef999d0d776d4d0b8948685318774a55d502bc70febc30a0e6d55fdad0761ebe7d2060ebb3e83d8c"} 06:28:06 INFO - Running post-run listener: copy_logs_to_upload_dir 06:28:06 INFO - Copying logs to upload dir... 06:28:06 INFO - mkdir: /builds/slave/test/build/upload/logs 06:28:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.686941 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-01-25 06:28:06.783636) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 06:28:06.787675) ========= 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.65oNHFqSC2/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.V2WdmIjwKT/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/Firefox/sha512/51aa62216728d03edd2056b3e7e05a2b4e34992d094fada82dff5679c8efcc2f603da4317514d955fde5212c14943501af2821e9d3cab60fe90cedcb09172bbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/581e38fee656b3dca6f79260347314f34037797dd63d3053bf000aa7879fb578d60a4ab7fe0fb19ccc4b00050ff09ae5b60d26e8fc66fe277b95f4a7bc49dae3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ed8e33a3d0b4fe8e5da618a4162acf0c997b63795b2aaff619d5b1922a425c1803ff5cb3b4f52c74ddc44f0bd521ad70672fa270cd5347eca520ce09220e3b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d6995ed64b611015152d0bddbcc4841434e6864f9dafa87ef999d0d776d4d0b8948685318774a55d502bc70febc30a0e6d55fdad0761ebe7d2060ebb3e83d8c"} build_url:https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/NeW48s0uSvu7U9oTpzBVsA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51aa62216728d03edd2056b3e7e05a2b4e34992d094fada82dff5679c8efcc2f603da4317514d955fde5212c14943501af2821e9d3cab60fe90cedcb09172bbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/581e38fee656b3dca6f79260347314f34037797dd63d3053bf000aa7879fb578d60a4ab7fe0fb19ccc4b00050ff09ae5b60d26e8fc66fe277b95f4a7bc49dae3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ed8e33a3d0b4fe8e5da618a4162acf0c997b63795b2aaff619d5b1922a425c1803ff5cb3b4f52c74ddc44f0bd521ad70672fa270cd5347eca520ce09220e3b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d6995ed64b611015152d0bddbcc4841434e6864f9dafa87ef999d0d776d4d0b8948685318774a55d502bc70febc30a0e6d55fdad0761ebe7d2060ebb3e83d8c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 06:28:06.822630) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 06:28:06.823017) ========= 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.65oNHFqSC2/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.V2WdmIjwKT/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005343 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 06:28:06.872567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 06:28:06.872951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 06:28:06.873373) ========= ========= Total master_lag: 0.19 =========