builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0222 starttime: 1486083995.75 results: success (0) buildid: 20170202155609 builduid: 452cfd6e3dca4c2790fbd1a9887e392a revision: b4b23e49820c6de9658539e6a748f5b9515f42f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.750893) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.751807) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.752445) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.780708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.781681) ========= 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.kGWeghRpsy/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.sN61uGbfRQ/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-02-02 17:06:35-- 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% 54.8M=0s 2017-02-02 17:06:35 (54.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083248 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.894164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.894519) ========= 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.kGWeghRpsy/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.sN61uGbfRQ/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.025048 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:35.944745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 17:06:35.945123) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b4b23e49820c6de9658539e6a748f5b9515f42f5 --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 b4b23e49820c6de9658539e6a748f5b9515f42f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kGWeghRpsy/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.sN61uGbfRQ/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-02-02 17:06:36,052 truncating revision to first 12 chars 2017-02-02 17:06:36,052 Setting DEBUG logging. 2017-02-02 17:06:36,052 attempt 1/10 2017-02-02 17:06:36,052 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b4b23e49820c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 17:06:37,672 unpacking tar archive at: mozilla-central-b4b23e49820c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.890682 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 17:06:37.861133) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:37.861489) ========= script_repo_revision: b4b23e49820c6de9658539e6a748f5b9515f42f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 17:06:37.861968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2017-02-02 17:06:37.862291) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2017-02-02 17:06:40.903960) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-02-02 17:06:40.904307) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kGWeghRpsy/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.sN61uGbfRQ/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:06:41 INFO - MultiFileLogger online at 20170202 17:06:41 in /builds/slave/test 17:06:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:06:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:06:41 INFO - {'append_to_log': False, 17:06:41 INFO - 'base_work_dir': '/builds/slave/test', 17:06:41 INFO - 'blob_upload_branch': 'Firefox', 17:06:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:06:41 INFO - 'branch': 'Firefox', 17:06:41 INFO - 'buildbot_json_path': 'buildprops.json', 17:06:41 INFO - 'config_files': ('talos/mac_config.py',), 17:06:41 INFO - 'default_actions': ('clobber', 17:06:41 INFO - 'read-buildbot-config', 17:06:41 INFO - 'download-and-extract', 17:06:41 INFO - 'populate-webroot', 17:06:41 INFO - 'create-virtualenv', 17:06:41 INFO - 'install', 17:06:41 INFO - 'run-tests'), 17:06:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:06:41 INFO - 'download_minidump_stackwalk': True, 17:06:41 INFO - 'download_symbols': 'ondemand', 17:06:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:06:41 INFO - 'tooltool.py': '/tools/tooltool.py', 17:06:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:06:41 INFO - '/tools/misc-python/virtualenv.py')}, 17:06:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:06:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:06:41 INFO - 'installer_path': 'installer.exe', 17:06:41 INFO - 'log_level': 'info', 17:06:41 INFO - 'log_name': 'talos', 17:06:41 INFO - 'log_to_console': True, 17:06:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:06:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:06:41 INFO - 'opt_config_files': (), 17:06:41 INFO - 'pip_index': False, 17:06:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:06:41 INFO - 'cmd': ('bash', 17:06:41 INFO - '-c', 17:06:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:06:41 INFO - 'enabled': True, 17:06:41 INFO - 'halt_on_failure': False, 17:06:41 INFO - 'name': 'check_screen_resolution'},), 17:06:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:06:41 INFO - 'cmd': ('bash', 17:06:41 INFO - '-c', 17:06:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:06:41 INFO - 'enabled': True, 17:06:41 INFO - 'halt_on_failure': False, 17:06:41 INFO - 'name': 'check_screen_resolution'},), 17:06:41 INFO - 'run_cmd_checks_enabled': True, 17:06:41 INFO - 'sps_profile': False, 17:06:41 INFO - 'sps_profile_interval': 0, 17:06:41 INFO - 'suite': 'dromaeojs', 17:06:41 INFO - 'system_bits': '32', 17:06:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:06:41 INFO - 'title': 't-yosemite-r7-0222', 17:06:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:06:41 INFO - 'use_talos_json': True, 17:06:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:06:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:06:41 INFO - 'work_dir': 'build'} 17:06:41 INFO - [mozharness: 2017-02-03 01:06:41.119328Z] Running clobber step. 17:06:41 INFO - Running pre-action listener: _resource_record_pre_action 17:06:41 INFO - Running main action method: clobber 17:06:41 INFO - rmtree: /builds/slave/test/build 17:06:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:06:43 INFO - Running post-action listener: _resource_record_post_action 17:06:43 INFO - [mozharness: 2017-02-03 01:06:43.577660Z] Finished clobber step (success) 17:06:43 INFO - [mozharness: 2017-02-03 01:06:43.577805Z] Running read-buildbot-config step. 17:06:43 INFO - Running pre-action listener: _resource_record_pre_action 17:06:43 INFO - Running main action method: read_buildbot_config 17:06:43 INFO - Using buildbot properties: 17:06:43 INFO - { 17:06:43 INFO - "project": "", 17:06:43 INFO - "product": "firefox", 17:06:43 INFO - "script_repo_revision": "production", 17:06:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:06:43 INFO - "repository": "", 17:06:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:06:43 INFO - "buildid": "20170202155609", 17:06:43 INFO - "pgo_build": "False", 17:06:43 INFO - "basedir": "/builds/slave/test", 17:06:43 INFO - "buildnumber": 55, 17:06:43 INFO - "slavename": "t-yosemite-r7-0222", 17:06:43 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:06:43 INFO - "platform": "yosemite_r7", 17:06:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:06:43 INFO - "branch": "mozilla-central", 17:06:43 INFO - "repo_path": "mozilla-central", 17:06:43 INFO - "revision": "b4b23e49820c6de9658539e6a748f5b9515f42f5", 17:06:43 INFO - "stage_platform": "macosx64", 17:06:43 INFO - "builduid": "452cfd6e3dca4c2790fbd1a9887e392a", 17:06:43 INFO - "slavebuilddir": "test" 17:06:43 INFO - } 17:06:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 17:06:43 INFO - Running post-action listener: _resource_record_post_action 17:06:43 INFO - [mozharness: 2017-02-03 01:06:43.581253Z] Finished read-buildbot-config step (success) 17:06:43 INFO - [mozharness: 2017-02-03 01:06:43.581373Z] Running download-and-extract step. 17:06:43 INFO - Running pre-action listener: _resource_record_pre_action 17:06:43 INFO - Running main action method: download_and_extract 17:06:43 INFO - mkdir: /builds/slave/test/build/tests 17:06: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')]} 17:06:43 INFO - trying https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 17:06:43 INFO - Downloading https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/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 17:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/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 17:06:45 INFO - Downloaded 1199 bytes. 17:06:45 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 17:06:45 INFO - Using the following test package requirements: 17:06:45 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:06:45 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 17:06:45 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 17:06:45 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'jsshell-mac.zip'], 17:06:45 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 17:06:45 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 17:06:45 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 17:06:45 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 17:06:45 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 17:06:45 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 17:06:45 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 17:06:45 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:06:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:06:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:06:45 INFO - Fetch https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:06:47 INFO - Expected file size: 34848644 17:06:47 INFO - Obtained file size: 34848644 17:06:54 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 17:06:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 17:06:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:06:54 INFO - Fetch https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 17:06:58 INFO - Expected file size: 34848644 17:06:58 INFO - Obtained file size: 34848644 17:07:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 17:07:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:07:05 INFO - Fetch https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 17:07:07 INFO - Expected file size: 14403454 17:07:07 INFO - Obtained file size: 14403454 17: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')]} 17:07:08 INFO - trying https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:07:08 INFO - Downloading https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 17:07:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/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 17:07:15 INFO - Downloaded 70235826 bytes. 17:07:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:07:15 INFO - mkdir: /builds/slave/test/properties 17:07:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:07:15 INFO - Writing to file /builds/slave/test/properties/build_url 17:07:15 INFO - Contents: 17:07:15 INFO - build_url:https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 17:07:16 INFO - Running post-action listener: _resource_record_post_action 17:07:16 INFO - Running post-action listener: set_extra_try_arguments 17:07:16 INFO - [mozharness: 2017-02-03 01:07:16.153291Z] Finished download-and-extract step (success) 17:07:16 INFO - [mozharness: 2017-02-03 01:07:16.153430Z] Running populate-webroot step. 17:07:16 INFO - Running pre-action listener: _resource_record_pre_action 17:07:16 INFO - Running main action method: populate_webroot 17:07:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:07:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:07:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:07:16 INFO - '--tppagecycles', 17:07:16 INFO - '7'], 17:07:16 INFO - 'tests': ['tsvgm']}}, 17:07:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:07:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:07:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:07:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:07:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:07:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:07:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['damp', 'tps']}, 17:07:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'tests': ['damp', 'tps']}, 17:07:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['dromaeo_dom']}, 17:07:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:07:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['basic_compositor_video']}, 17:07:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:07:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['a11yr', 17:07:16 INFO - 'ts_paint', 17:07:16 INFO - 'tpaint', 17:07:16 INFO - 'sessionrestore', 17:07:16 INFO - 'sessionrestore_no_auto_restore', 17:07:16 INFO - 'tabpaint']}, 17:07:16 INFO - 'other-e10s': {'tests': ['a11yr', 17:07:16 INFO - 'ts_paint', 17:07:16 INFO - 'tpaint', 17:07:16 INFO - 'sessionrestore', 17:07:16 INFO - 'sessionrestore_no_auto_restore', 17:07:16 INFO - 'tabpaint']}, 17:07:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:07:16 INFO - 'ts_paint', 17:07:16 INFO - 'tpaint', 17:07:16 INFO - 'sessionrestore', 17:07:16 INFO - 'sessionrestore_no_auto_restore', 17:07:16 INFO - 'tabpaint']}, 17:07:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:07:16 INFO - 'ts_paint', 17:07:16 INFO - 'tpaint', 17:07:16 INFO - 'sessionrestore', 17:07:16 INFO - 'sessionrestore_no_auto_restore', 17:07:16 INFO - 'tabpaint']}, 17:07:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['a11yr', 17:07:16 INFO - 'ts_paint', 17:07:16 INFO - 'tpaint', 17:07:16 INFO - 'sessionrestore', 17:07:16 INFO - 'sessionrestore_no_auto_restore', 17:07:16 INFO - 'tabpaint']}, 17:07:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['a11yr', 17:07:16 INFO - 'ts_paint', 17:07:16 INFO - 'tpaint', 17:07:16 INFO - 'sessionrestore', 17:07:16 INFO - 'sessionrestore_no_auto_restore', 17:07:16 INFO - 'tabpaint']}, 17:07:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['tsvgx', 17:07:16 INFO - 'tsvgr_opacity', 17:07:16 INFO - 'tart', 17:07:16 INFO - 'tscrollx', 17:07:16 INFO - 'cart', 17:07:16 INFO - 'tsvg_static']}, 17:07:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:07:16 INFO - 'tsvgr_opacity', 17:07:16 INFO - 'tart', 17:07:16 INFO - 'tscrollx', 17:07:16 INFO - 'cart', 17:07:16 INFO - 'tsvg_static']}, 17:07:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'talos_options': ['--disable-e10s'], 17:07:16 INFO - 'tests': ['tp5o']}, 17:07:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:07:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'talos_options': ['--disable-e10s', 17:07:16 INFO - '--xperf_path', 17:07:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:07:16 INFO - 'tests': ['tp5n']}, 17:07:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:07:16 INFO - 'talos_options': ['--xperf_path', 17:07:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:07:16 INFO - 'tests': ['tp5n']}}, 17:07:16 INFO - 'talos.zip': {'path': '', 17:07:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:07:16 INFO - Running post-action listener: _resource_record_post_action 17:07:16 INFO - [mozharness: 2017-02-03 01:07:16.171873Z] Finished populate-webroot step (success) 17:07:16 INFO - [mozharness: 2017-02-03 01:07:16.172012Z] Running create-virtualenv step. 17:07:16 INFO - Running pre-action listener: _resource_record_pre_action 17:07:16 INFO - Running main action method: create_virtualenv 17:07:16 INFO - Creating virtualenv /builds/slave/test/build/venv 17:07:16 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 17:07:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:07:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:07:16 INFO - Using real prefix '/tools/python27' 17:07:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:07:17 INFO - Installing distribute.............................................................................................................................................................................................done. 17:07:19 INFO - Installing pip.................done. 17:07:19 INFO - Return code: 0 17:07:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:07:20 INFO - Reading from file tmpfile_stdout 17:07:20 INFO - Output received: 17:07:20 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) 17:07:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:20 INFO - 'HOME': '/Users/cltbld', 17:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:20 INFO - 'LOGNAME': 'cltbld', 17:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:20 INFO - 'MOZ_NO_REMOTE': '1', 17:07:20 INFO - 'NO_EM_RESTART': '1', 17:07:20 INFO - 'PAGER': '/bin/cat', 17:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:20 INFO - 'PWD': '/builds/slave/test', 17:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:20 INFO - 'SHELL': '/bin/bash', 17:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:20 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:20 INFO - 'USER': 'cltbld', 17:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:20 INFO - 'XPC_FLAGS': '0x0', 17:07:20 INFO - 'XPC_SERVICE_NAME': '0', 17:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:20 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 17:07:20 INFO - Cleaning up... 17:07:20 INFO - Return code: 0 17:07:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:07:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:20 INFO - 'HOME': '/Users/cltbld', 17:07:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:20 INFO - 'LOGNAME': 'cltbld', 17:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:20 INFO - 'MOZ_NO_REMOTE': '1', 17:07:20 INFO - 'NO_EM_RESTART': '1', 17:07:20 INFO - 'PAGER': '/bin/cat', 17:07:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:20 INFO - 'PWD': '/builds/slave/test', 17:07:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:20 INFO - 'SHELL': '/bin/bash', 17:07:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:20 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:20 INFO - 'USER': 'cltbld', 17:07:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:20 INFO - 'XPC_FLAGS': '0x0', 17:07:20 INFO - 'XPC_SERVICE_NAME': '0', 17:07:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:20 INFO - Downloading/unpacking psutil>=3.1.1 17:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:07:24 INFO - Installing collected packages: psutil 17:07:24 INFO - Running setup.py install for psutil 17:07:24 INFO - building 'psutil._psutil_osx' extension 17:07:24 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 17:07:24 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 17:07:24 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 17:07:25 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 17:07:25 INFO - building 'psutil._psutil_posix' extension 17:07:25 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 17:07:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:07:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:07:25 INFO - ^ 17:07:25 INFO - 1 warning generated. 17:07:25 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 17:07:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:07:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:07:25 INFO - Successfully installed psutil 17:07:25 INFO - Cleaning up... 17:07:25 INFO - Return code: 0 17:07:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:25 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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:25 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 17:07:25 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 17:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:25 INFO - 'HOME': '/Users/cltbld', 17:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:25 INFO - 'LOGNAME': 'cltbld', 17:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:25 INFO - 'MOZ_NO_REMOTE': '1', 17:07:25 INFO - 'NO_EM_RESTART': '1', 17:07:25 INFO - 'PAGER': '/bin/cat', 17:07:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:25 INFO - 'PWD': '/builds/slave/test', 17:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:25 INFO - 'SHELL': '/bin/bash', 17:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:25 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:25 INFO - 'USER': 'cltbld', 17:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:25 INFO - 'XPC_FLAGS': '0x0', 17:07:25 INFO - 'XPC_SERVICE_NAME': '0', 17:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:07:25 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 17:07:25 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 17:07:25 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 17:07:25 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 17:07:25 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 17:07:25 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 17:07:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:07:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:07:28 INFO - Installing collected packages: mozsystemmonitor 17:07:28 INFO - Running setup.py install for mozsystemmonitor 17:07:28 INFO - Successfully installed mozsystemmonitor 17:07:28 INFO - Cleaning up... 17:07:28 INFO - Return code: 0 17:07:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:28 INFO - 'HOME': '/Users/cltbld', 17:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:28 INFO - 'LOGNAME': 'cltbld', 17:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:28 INFO - 'MOZ_NO_REMOTE': '1', 17:07:28 INFO - 'NO_EM_RESTART': '1', 17:07:28 INFO - 'PAGER': '/bin/cat', 17:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:28 INFO - 'PWD': '/builds/slave/test', 17:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:28 INFO - 'SHELL': '/bin/bash', 17:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:28 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:28 INFO - 'USER': 'cltbld', 17:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:28 INFO - 'XPC_FLAGS': '0x0', 17:07:28 INFO - 'XPC_SERVICE_NAME': '0', 17:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:28 INFO - Downloading/unpacking jsonschema==2.5.1 17:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:07:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:07:31 INFO - Downloading functools32-3.2.3-2.tar.gz 17:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:07:32 INFO - warning: no files found matching '*.txt' 17:07:32 INFO - no previously-included directories found matching 'build' 17:07:32 INFO - no previously-included directories found matching 'dist' 17:07:32 INFO - no previously-included directories found matching '.git*' 17:07:32 INFO - Installing collected packages: jsonschema, functools32 17:07:32 INFO - Running setup.py install for functools32 17:07:32 INFO - warning: no files found matching '*.txt' 17:07:32 INFO - no previously-included directories found matching 'build' 17:07:32 INFO - no previously-included directories found matching 'dist' 17:07:32 INFO - no previously-included directories found matching '.git*' 17:07:32 INFO - Successfully installed jsonschema functools32 17:07:32 INFO - Cleaning up... 17:07:32 INFO - Return code: 0 17:07:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:33 INFO - 'HOME': '/Users/cltbld', 17:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:33 INFO - 'LOGNAME': 'cltbld', 17:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:33 INFO - 'MOZ_NO_REMOTE': '1', 17:07:33 INFO - 'NO_EM_RESTART': '1', 17:07:33 INFO - 'PAGER': '/bin/cat', 17:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:33 INFO - 'PWD': '/builds/slave/test', 17:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:33 INFO - 'SHELL': '/bin/bash', 17:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:33 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:33 INFO - 'USER': 'cltbld', 17:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:33 INFO - 'XPC_FLAGS': '0x0', 17:07:33 INFO - 'XPC_SERVICE_NAME': '0', 17:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:07:33 INFO - Cleaning up... 17:07:33 INFO - Return code: 0 17:07:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:33 INFO - 'HOME': '/Users/cltbld', 17:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:33 INFO - 'LOGNAME': 'cltbld', 17:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:33 INFO - 'MOZ_NO_REMOTE': '1', 17:07:33 INFO - 'NO_EM_RESTART': '1', 17:07:33 INFO - 'PAGER': '/bin/cat', 17:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:33 INFO - 'PWD': '/builds/slave/test', 17:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:33 INFO - 'SHELL': '/bin/bash', 17:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:33 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:33 INFO - 'USER': 'cltbld', 17:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:33 INFO - 'XPC_FLAGS': '0x0', 17:07:33 INFO - 'XPC_SERVICE_NAME': '0', 17:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:34 INFO - Downloading/unpacking blobuploader==1.2.4 17:07:34 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 17:07:34 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 17:07:34 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 17:07:34 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 17:07:34 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 17:07:34 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 17:07:37 INFO - Downloading blobuploader-1.2.4.tar.gz 17:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:07:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17: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 17: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 17: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 17: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 17: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 17: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 17:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:07:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17: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 17: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 17: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 17: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 17: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 17: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 17:07:38 INFO - Downloading docopt-0.6.1.tar.gz 17:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:07:38 INFO - Installing collected packages: blobuploader, requests, docopt 17:07:38 INFO - Running setup.py install for blobuploader 17:07:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:07:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:07:38 INFO - Running setup.py install for requests 17:07:39 INFO - Running setup.py install for docopt 17:07:39 INFO - Successfully installed blobuploader requests docopt 17:07:39 INFO - Cleaning up... 17:07:39 INFO - Return code: 0 17:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:39 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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:39 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 17:07:39 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 17:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:39 INFO - 'HOME': '/Users/cltbld', 17:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:39 INFO - 'LOGNAME': 'cltbld', 17:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:39 INFO - 'MOZ_NO_REMOTE': '1', 17:07:39 INFO - 'NO_EM_RESTART': '1', 17:07:39 INFO - 'PAGER': '/bin/cat', 17:07:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:39 INFO - 'PWD': '/builds/slave/test', 17:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:39 INFO - 'SHELL': '/bin/bash', 17:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:39 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:39 INFO - 'USER': 'cltbld', 17:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:39 INFO - 'XPC_FLAGS': '0x0', 17:07:39 INFO - 'XPC_SERVICE_NAME': '0', 17:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:07:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-dJ4XQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:07:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-qUPrWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:07:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-k5lMMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:07:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-TNmJxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:07:39 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-jPTFXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-53azYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-52wk1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-iXrnJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-baHf73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-WuhnG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-O4QhYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-wODA0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-dgPx7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:07:40 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-tNhzA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:41 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ycT9qD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:07:41 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-DkvEBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:07:41 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Dn5yBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:07:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:07:41 INFO - Running setup.py install for manifestparser 17:07:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:07:41 INFO - Running setup.py install for mozcrash 17:07:41 INFO - Running setup.py install for mozdebug 17:07:41 INFO - Running setup.py install for mozdevice 17:07:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:07:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:07:41 INFO - Running setup.py install for mozfile 17:07:42 INFO - Running setup.py install for mozhttpd 17:07:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Running setup.py install for mozinfo 17:07:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Running setup.py install for mozInstall 17:07:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Running setup.py install for mozleak 17:07:42 INFO - Running setup.py install for mozlog 17:07:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Running setup.py install for moznetwork 17:07:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:07:42 INFO - Running setup.py install for mozprocess 17:07:43 INFO - Running setup.py install for mozprofile 17:07:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:07:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:07:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:07:43 INFO - Running setup.py install for mozrunner 17:07:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:07:43 INFO - Running setup.py install for mozscreenshot 17:07:43 INFO - Running setup.py install for moztest 17:07:43 INFO - Running setup.py install for mozversion 17:07:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:07:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:07:43 INFO - Cleaning up... 17:07:43 INFO - Return code: 0 17:07:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17: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')]} 17:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:43 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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:43 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 17:07:43 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 17:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:43 INFO - 'HOME': '/Users/cltbld', 17:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:43 INFO - 'LOGNAME': 'cltbld', 17:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:43 INFO - 'MOZ_NO_REMOTE': '1', 17:07:43 INFO - 'NO_EM_RESTART': '1', 17:07:43 INFO - 'PAGER': '/bin/cat', 17:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:43 INFO - 'PWD': '/builds/slave/test', 17:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:43 INFO - 'SHELL': '/bin/bash', 17:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:43 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:43 INFO - 'USER': 'cltbld', 17:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:43 INFO - 'XPC_FLAGS': '0x0', 17:07:43 INFO - 'XPC_SERVICE_NAME': '0', 17:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-3Z2qFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-QbN1qQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-nCgUln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-EfMLS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-cd5XEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-HqMiLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-jpsAf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:07:44 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)) 17:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:07:44 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-wbwdsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Q_zZly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-OqlUSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7LsCG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-HcbF7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-fyohLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4uADmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ZviJPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-JX6nHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:07:45 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)) 17:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:07:45 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-p4wmEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:07:46 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)) 17:07:46 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)) 17:07:46 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)) 17:07:46 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)) 17:07:46 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)) 17:07:46 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)) 17:07:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:07:46 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 17:07:46 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 17:07:46 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 17:07:46 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 17:07:46 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 17:07:46 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 17:07:48 INFO - Downloading blessings-1.6.tar.gz 17:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:07:48 INFO - Installing collected packages: blessings 17:07:48 INFO - Running setup.py install for blessings 17:07:48 INFO - Successfully installed blessings 17:07:48 INFO - Cleaning up... 17:07:48 INFO - Return code: 0 17:07:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:07:49 INFO - Reading from file tmpfile_stdout 17:07:49 INFO - Current package versions: 17:07:49 INFO - blessings == 1.6 17:07:49 INFO - blobuploader == 1.2.4 17:07:49 INFO - docopt == 0.6.1 17:07:49 INFO - functools32 == 3.2.3-2 17:07:49 INFO - jsonschema == 2.5.1 17:07:49 INFO - manifestparser == 1.1 17:07:49 INFO - mozInstall == 1.12 17:07:49 INFO - mozcrash == 1.0 17:07:49 INFO - mozdebug == 0.1 17:07:49 INFO - mozdevice == 0.49 17:07:49 INFO - mozfile == 1.2 17:07:49 INFO - mozhttpd == 0.7 17:07:49 INFO - mozinfo == 0.9 17:07:49 INFO - mozleak == 0.1 17:07:49 INFO - mozlog == 3.4 17:07:49 INFO - moznetwork == 0.27 17:07:49 INFO - mozprocess == 0.24 17:07:49 INFO - mozprofile == 0.28 17:07:49 INFO - mozrunner == 6.13 17:07:49 INFO - mozscreenshot == 0.1 17:07:49 INFO - mozsystemmonitor == 0.3 17:07:49 INFO - moztest == 0.8 17:07:49 INFO - mozversion == 1.4 17:07:49 INFO - psutil == 3.1.1 17:07:49 INFO - requests == 1.2.3 17:07:49 INFO - wsgiref == 0.1.2 17:07:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17: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')]} 17:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:49 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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:49 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 17:07:49 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 17:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:49 INFO - 'HOME': '/Users/cltbld', 17:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:49 INFO - 'LOGNAME': 'cltbld', 17:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:49 INFO - 'MOZ_NO_REMOTE': '1', 17:07:49 INFO - 'NO_EM_RESTART': '1', 17:07:49 INFO - 'PAGER': '/bin/cat', 17:07:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:49 INFO - 'PWD': '/builds/slave/test', 17:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:49 INFO - 'SHELL': '/bin/bash', 17:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:49 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:49 INFO - 'USER': 'cltbld', 17:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:49 INFO - 'XPC_FLAGS': '0x0', 17:07:49 INFO - 'XPC_SERVICE_NAME': '0', 17:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 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)) 17:07:49 INFO - Cleaning up... 17:07:49 INFO - Return code: 0 17:07:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17: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')]} 17:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:49 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 0x105d65b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d60d63a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, '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.kGWeghRpsy/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.sN61uGbfRQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 17:07:49 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 17:07:49 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 17:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:49 INFO - 'HOME': '/Users/cltbld', 17:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:49 INFO - 'LOGNAME': 'cltbld', 17:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:49 INFO - 'MOZ_NO_REMOTE': '1', 17:07:49 INFO - 'NO_EM_RESTART': '1', 17:07:49 INFO - 'PAGER': '/bin/cat', 17:07:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:49 INFO - 'PWD': '/builds/slave/test', 17:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:49 INFO - 'SHELL': '/bin/bash', 17:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:07:49 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:49 INFO - 'USER': 'cltbld', 17:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:49 INFO - 'XPC_FLAGS': '0x0', 17:07:49 INFO - 'XPC_SERVICE_NAME': '0', 17:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:07:49 INFO - Cleaning up... 17:07:49 INFO - Return code: 0 17:07:49 INFO - Running post-action listener: _resource_record_post_action 17:07:49 INFO - Running post-action listener: _start_resource_monitoring 17:07:49 INFO - Starting resource monitoring. 17:07:49 INFO - [mozharness: 2017-02-03 01:07:49.831606Z] Finished create-virtualenv step (success) 17:07:49 INFO - [mozharness: 2017-02-03 01:07:49.832551Z] Running install step. 17:07:49 INFO - Running pre-action listener: _resource_record_pre_action 17:07:49 INFO - Running main action method: install 17:07:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:07:50 INFO - Reading from file tmpfile_stdout 17:07:50 INFO - Detecting whether we're running mozinstall >=1.0... 17:07:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:07:50 INFO - Reading from file tmpfile_stdout 17:07:50 INFO - Output received: 17:07:50 INFO - Usage: mozinstall [options] installer 17:07:50 INFO - Options: 17:07:50 INFO - -h, --help show this help message and exit 17:07:50 INFO - -d DEST, --destination=DEST 17:07:50 INFO - Directory to install application into. [default: 17:07:50 INFO - "/builds/slave/test"] 17:07:50 INFO - --app=APP Application being installed. [default: firefox] 17:07:50 INFO - mkdir: /builds/slave/test/build/application 17:07:50 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'] 17:07:50 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 17:08:12 INFO - Reading from file tmpfile_stdout 17:08:12 INFO - Output received: 17:08:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:08:12 INFO - Running post-action listener: _resource_record_post_action 17:08:12 INFO - [mozharness: 2017-02-03 01:08:12.224408Z] Finished install step (success) 17:08:12 INFO - [mozharness: 2017-02-03 01:08:12.224515Z] Running run-tests step. 17:08:12 INFO - Running pre-action listener: _resource_record_pre_action 17:08:12 INFO - Running main action method: run_tests 17:08:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:08:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:08:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:08:12 INFO - 2017-02-02 17:08:12.239 screenresolution[1626:7454] starting screenresolution argv=screenresolution get 17:08:12 INFO - 2017-02-02 17:08:12.241 screenresolution[1626:7454] Display 0: 1600x1200x32@60 17:08:12 INFO - 2017-02-02 17:08:12.247 screenresolution[1627:7456] starting screenresolution argv=screenresolution list 17:08:12 INFO - Available Modes on Display 0 17:08:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:08:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:08:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:08:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:08:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:08:12 INFO - Intel Iris: 17:08:12 INFO - Chipset Model: Intel Iris 17:08:12 INFO - Type: GPU 17:08:12 INFO - Bus: Built-In 17:08:12 INFO - VRAM (Dynamic, Max): 1536 MB 17:08:12 INFO - Vendor: Intel (0x8086) 17:08:12 INFO - Device ID: 0x0a2e 17:08:12 INFO - Revision ID: 0x0009 17:08:12 INFO - Displays: 17:08:12 INFO - Display: 17:08:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:08:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:08:12 INFO - Display Serial Number: 1600x1200 60 17:08:12 INFO - Main Display: Yes 17:08:12 INFO - Mirror: Off 17:08:12 INFO - Online: Yes 17:08:12 INFO - Rotation: Supported 17:08:12 INFO - Return code: 0 17:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:08:12 INFO - Python 2.7.3 17:08:12 INFO - Return code: 0 17:08:12 INFO - grabbing minidump binary from tooltool 17:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:08:12 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 0x105b43d30>, '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 0x105a6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b38be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:08:12 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 17:08:12 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 17:08:12 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 17:08:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:08:12 INFO - Return code: 0 17:08:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:08:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:08:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:08:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:08:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0222 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kGWeghRpsy/Render', 17:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:08:12 INFO - 'HOME': '/Users/cltbld', 17:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:08:12 INFO - 'LOGNAME': 'cltbld', 17:08:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:08:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:12 INFO - 'MOZ_NO_REMOTE': '1', 17:08:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:08:12 INFO - 'NO_EM_RESTART': '1', 17:08:12 INFO - 'PAGER': '/bin/cat', 17:08:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:12 INFO - 'PWD': '/builds/slave/test', 17:08:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:08:12 INFO - 'SHELL': '/bin/bash', 17:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sN61uGbfRQ/Listeners', 17:08:12 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 17:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:08:12 INFO - 'USER': 'cltbld', 17:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:12 INFO - 'XPC_FLAGS': '0x0', 17:08:12 INFO - 'XPC_SERVICE_NAME': '0', 17:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:08:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:08:12 INFO - mozversion application_buildid: 20170202155609 17:08:12 INFO - mozversion application_changeset: b4b23e49820c6de9658539e6a748f5b9515f42f5 17:08:12 INFO - mozversion application_display_name: Nightly 17:08:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:08:12 INFO - mozversion application_name: Firefox 17:08:12 INFO - mozversion application_remotingname: firefox 17:08:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:08:12 INFO - mozversion application_vendor: Mozilla 17:08:12 INFO - mozversion application_version: 54.0a1 17:08:12 INFO - mozversion platform_buildid: 20170202155609 17:08:12 INFO - mozversion platform_changeset: b4b23e49820c6de9658539e6a748f5b9515f42f5 17:08:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:08:12 INFO - mozversion platform_version: 54.0a1 17:08:12 INFO - using testdate: 1486084092 17:08:12 INFO - actual date: 1486084092 17:08:12 INFO - starting webserver on 'localhost:49241' 17:08:12 INFO - SUITE-START | Running 2 tests 17:08:12 INFO - TEST-START | dromaeo_css 17:08:12 INFO - operating with platform_type : mac_ 17:08:12 INFO - Initialising browser for dromaeo_css test... 17:08:12 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp4DY1w4/profile http://localhost:49241/getInfo.html) 17:08:15 INFO - PROCESS | 1637 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:08:15 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 17:08:15 INFO - PROCESS | 1637 | colorDepth:24 17:08:15 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 17:08:15 INFO - PROCESS | 1637 | __metrics 17:08:15 INFO - PROCESS | 1637 | 1486084095373 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:08:15 INFO - PROCESS | 1637 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:08:15 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:08:16 INFO - TEST-INFO | 1637: exit 0 17:08:16 INFO - Browser initialized. 17:08:16 INFO - Running cycle 1/1 for dromaeo_css test... 17:08:16 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp4DY1w4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:08:28 INFO - PROCESS | 1639 | RSS: Main: 237256704 17:08:28 INFO - PROCESS | 1639 | 17:11:31 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 17:11:34 INFO - PROCESS | 1639 | RSS: Main: 474787840 17:11:34 INFO - PROCESS | 1639 | 17:13:16 INFO - PROCESS | 1639 | ************************* 17:13:16 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 17:13:16 INFO - PROCESS | 1639 | 17:13:16 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:13:16 INFO - PROCESS | 1639 | Full stack: 17:13:16 INFO - PROCESS | 1639 | ************************* 17:14:37 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 17:14:38 INFO - PROCESS | 1639 | RSS: Main: 373506048 17:14:38 INFO - PROCESS | 1639 | 17:17:40 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 17:17:41 INFO - PROCESS | 1639 | RSS: Main: 363667456 17:17:41 INFO - PROCESS | 1639 | 17:20:44 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 17:20:44 INFO - PROCESS | 1639 | RSS: Main: 347070464 17:20:44 INFO - PROCESS | 1639 | 17:23:48 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 17:23:48 INFO - PROCESS | 1639 | RSS: Main: 323727360 17:23:48 INFO - PROCESS | 1639 | 17:26:51 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 17:26:52 INFO - PROCESS | 1639 | RSS: Main: 496988160 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | __start_tp_report 17:26:52 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 17:26:52 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 17:26:52 INFO - PROCESS | 1639 | |i|pagename|runs| 17:26:52 INFO - PROCESS | 1639 | |0;dojo.html;3160.9209769755753;3353.7987720736755;3386.661333866612;3398.6771256730603;3454.516367708521;4835.6943625221165;5604.915926261109;5626.324841019078;5636.436356364362;5648.774049038038;5676.403977582357;5709.371969083408;5780.670445797469;5785.82642520724;5830.854228644275;5111.897762044757;5124.205748109049;5161.251618515332;5220.347456567929;5258.658187217838;5275.657082289658;5302.125149350352;5310.0441920454305;5315.255864179018;5318.042752304584;5122.871928201787;5279.155335146358;5309.123994540896;5341.252224688546;5351.331083614548;5154.943236636481;5155.484451554815;5203.869903252411;5218.791248350061;5250.579953603703;5219.060569097561;5223.37319521656;5261.684298942076;5269.341332333458;5279.86800329991;5719.313682358102;5734.4265573442735;5745.396733342981;5756.079027355603;5782.190493330937;6136.20229370179;6142.2225331421305;6271.122042913996;6281.623102613858;6286.057091436275;5744.827655170351;5832.504237139849;6203.627782333029;6218.906716399258;6259.311475737665;5834.124881267801;5885.999380105392;5903.763849445974;5919.763209471659;5922.7038648067855;6232.563720539519;6325.399087086719;6333.778317758856;6336.922723137078;6338.207724034496;6095.299040610386;6097.470219637845;6110.16388879229;6138.987067133873;6180.289266734293;5312.787488500417;6152.415520525543;6180;6311.33730958252;6314.652694101776;6166.537509686791;6177.382261773877;6177.567570270128;6180.598261113013;6223.408776166257;6187.969060154671;6295.748170073146;6303.432691057735;6304.621722696653;6313.873722525524;4448.9110217796115;4892.755362231874;4960.057589058068;5059.494050594984;5079.187330027178;4445.466544014675;4464.99537604043;4476.910461790746;4479.641628669699;4524.072565124142;5923.7926672566255;5942.37605051472;5954.404559544011;5969.970150149222;5975.52195824333;6182.721777520022;6188.040853667689;6213;6224.097505861625;6226.159468471789;5809.941900581025;5816.680082595498;5895.056790913433;5951.732172052268;5958.344582095966;5519.641223320471;5538.584606154554;5550.555955523549;5562.916556251659;5567.471090246501;6497.870042599027;6524.249711283168;6532.934670653232;6536.052272420377;6541.8691626168165;6037.09443583466;6102.398363691312;6225.906611400742;6246.437820596168;6265.592736472115;6354.714037868214;6399.93600064012;6421.325760795236;6441.066045423481;6496.382843629847;6146.569740118328;6154.599951003171;6155.199824022849;6170.352113028068;6183.412575805291;6150.10823430609;6158.599691020069;6173.32093469709;6191.907121393093;6203.472704820141;5721.170430287501;6037.305709843425;6055.757769689163;6077.939220607737;6106.664133472702;6118.43644382056;6122.794608433986;6130.703893785035;6131.193168498027;6141.723622437091;5724.255846739898;5888.146218798164;5912.231409916683;5916.26046744157;6067.54493412987;6030.668313242813;6086.573939824169;6102.603330783485;6108.633481991094;6110.7250173741395;5995.850103747442;5998;6004.699765011819;6010.789622363196;6018.3680713524445 17:26:52 INFO - PROCESS | 1639 | |1;ext.html;496.8136948644258;498.9301497790308;501.89332521789436;507.24927107880336;512.4875124875124;39738.00654983627;42960;43441.6967490975;45337.77331113348;45426;43349.7832510837;43771;44050.05324281229;44140.55859441413;44261.77869110658;8418.621162047692;8638.568071596426;9236.630534778618;9547.570359333811;9569.47367894769;10006;11088;11444.599439019636;11449;11600.6519804406;11367.374794386305;11396.14528910335;11460.656180314561;11474.25417347874;11540.365279909602;8342.290905273;8536.975562932426;11301.152413569016;11471.770564588704;11567.669717982455;11237.775244495146;11443;11457.713557161054;11459.197856150073;11505.079593632572;42580.57419425828;42764;42828.57171428277;42834.78582607098;42948;3641.5448068991377;4047.150098479323;4074.6943979201383;4100.01599616093;4103.651189648884;29024.854875725487;31082;31130.533042004387;31162.844185778926;31182.376352472824;3146.889858854952;3266.738660907122;3295.8187299698507;3332.450145725952;3339.3154403347257;481648;484143;484154;485574.99999999645;488230;65488.05303761342;66765.321987972;66771.31658934645;66856.66571667111;66862.33137668562;67455;67629.66185169043;70134.64932675406;70207.64896175487;70265;16177.838221617869;16746.246418911178;16755.41356052532;16773.077480738433;16783.2447539861;11451.59919402817;11691.123165762601;11714.297142171497;12505.74988500225;12628.558000469939;18394.98827564497;18769.90615046916;18956.526086847938;18964.715529267072;19133.85196888191;6537.803865884031;6619.834504137435;6656.101426307483;6660.200775906826;6673.899891501631;867.6009035843583;869.9913000870037;882.9646814127362;902.1024081039408;909.6984628888922;25872.482550349265;30535.625896834696;30597.69402305993;30707.692923070485;30753.384932301677;1059.86751656043;1079.7840431913492;1097.4403054442291;1101.7631209290041;1170.4849866058908;15344.462943796914;15954.28205730745;16005.839941600669;16045.679086418206;16256.268467918972;606242;608862;611932;612926;614059;64229;64578.677106615105;64672.35327646758;64740;64792;1412.639776856895;1447.3269929482842;1457.2131049233508;1462.0423622527262;1471.9990406523452;28867.5669864948;29724.554131688474;30359;31336.216594584406;31511;53441;54071.72964135154;54185.18722219091;54206.45793542172;54243.72878135584;519347;526775;536826;537098;538742;35451.82274088613;36528;41451;42088;42777;48630.513694864014;48832;49750;49839.00321993612;50488.74755626198;35052;36989;39798.801005994785;40169.39745903872;40720.79639601783;39020.2195956085;39209.215815684096;39845;40491;40744.98137546585;37336.81331593324;38221.853344399715;38398.424023640226;38472.230555389295;38696.80651596724;30838.383232334778;34885.476717848745;34921.82539087288;35037.124071898405;35195.82402087973;25362.619560706236;25379.111731089324;25398.111066112597;25808.870955645103;25991.350216244744 17:26:52 INFO - PROCESS | 1639 | |2;jquery.html;334.6202060661149;376.0073406207614;392.7702294157919;401.6144501278772;426.96370808481277;15519.92240038801;16170.75743863843;18951.526211844695;22356.329310120702;22610.43473913151;18220;18318.908405457954;22119.44701382466;22130.114795408164;22290.331290061284;5692.658440493563;5698.487136157755;5914.1128830675525;5985.281766188064;6009.399060094;5376.946230537684;5860.882782344351;5899.262592175978;5922.792702255422;5979.043353063511;4165.708400411972;4822.807087716487;5918.112283157539;6422.03669449584;6454.93545064548;6326.841828954267;6516.641584712839;6542.3130571290285;6544.5746026508605;6551.639659818684;5742.425757424266;5761.366249712528;5773.220615216976;5784.2769653793275;5790.420957904218;23124.53750924989;26531.07141250047;27698.5845212322;27826.304342391602;27899.442011159663;2421.3462365161486;2712.389712314733;2746.231055304518;2758.8758505867286;2765.1842706401458;16010.759838602431;16206.83793162065;16351.59121021972;16591.585210369838;16957.660846783117;1844.2530775035998;1855.701817496202;1861.413654698779;1870.064967516242;1927.5373910261437;129338.35330823285;131284;131341.73165366965;132809;133414.3329283357;11289.266197697123;11421.42892855354;11518.136139789549;11548.249363791494;11614.187006909435;14831.77752333716;15018.549443516711;15057.774133388008;15067;15097;8451.746447606582;8509.27671147947;8600.311975042106;8773.341999350074;8890.64437422496;8629.137086291446;8640.654373824977;8653.913460865311;8819.647214111492;8851;6033.276006879115;7318.817029574197;7359.595222262827;7393.667284972189;7410.184879663233;1533.693261347735;1627.7070127377185;1929.5176205948512;1931.4591914264029;1940.302235543899;1036.8957060730297;1043.5408420295046;1043.8016776812533;1066.6959916423937;1110.8000559899135;22654.886725566266;23059.308220753403;23106;23119.306420807403;23166.3050108497;1072.0619457974271;1083;1086.8750093739163;1112.4437781109445;1138.3795831271977;9371.578278977462;11165.609203677832;11450.255733377304;12017.939910300567;12382.81425778614;405409;406937;407055;477955;632258;131038;140407;153828.46171538366;158733.2063339676;160087.59430636972;1037.5745944162857;1047.8061558611835;1081.799867024603;1089.340948726025;1092.9398883061508;19673;20080.49798755043;20086.09612567409;20174.19303227913;20198.990050497123;57392.1391179141;58413;59353.10970335362;59854;60050;89166;89929.55035224782;90069;90966.54516727374;91446;6116.449519543263;7525.36034437079;7691.30778229962;7886.684532618631;7980.760577182694;12790.68023299425;13131.146475479063;13284.335783210607;13312.46750129984;13760.105593136414;11427.371494567878;11538.865309917692;11800.350980696145;11881.287122772661;11904.583339583072;9505.477198754135;11148.498317575892;11420.885791141982;11578.07375409982;11804.645860624196;11385.829212561985;11493.482793274155;11874.46564904564;11906.642800715992;11974.401279935793;7195.820104497429;7250.709971601288;7573.2048134945035;7849.076976140882;8074.434789564909;10549.789004220027;10782.514786834454;11480.655580332603;11844.70388240301;11878.346690931736 17:26:52 INFO - PROCESS | 1639 | |3;mootools.html;1408.42254675583;1409.0770545292837;1435.0913285330512;1439.071798689845;1446.3346860444194;8020.5187688738715;8425;8427.789305267355;8436.409451338408;8437.535935523543;7475.73834915778;7680.385569154469;7683.346915512174;8029.036515618134;8265.628046737897;2224.92212772553;2308.515211805523;2312.4796920692793;2313.1094528606523;2315.085541211229;1951.2585217617286;2260.0394832196334;2304.7119110111234;2309.538092381522;2317.9072837086583;2023.2615095490187;2095.779943105967;2205.007746514067;2248.123231939545;2255.4023183856293;2249.5275992041693;2304.0783686525356;2305.7578954209735;2310.2722642367767;2313.4679023824615;2271.4548508357875;2315.7452680205165;2316.81465482761;2337.4039619896816;2338.649202619604;8329.50022998622;8366.623501942428;8375.539345336005;8393.580320983987;8401;5033.974830125862;5197.402298735656;5203.791848326063;5210.895782084375;5216.7913283469;3317.5189597508465;3514.5431093957877;3527.5414196154593;3589.9282014359565;3601.549806274216;1164.3072371938683;1171.2679575265458;1175.3770501634062;1214.5061574263616;1281.9294938778364;10302.33034852732;10541.156707463384;10553.84169237462;10590.311629744043;10746;4633.0733853229485;4720.12677654635;4736.573708366263;4739.028499157665;4754.263089221176;9487.952560237292;9498.335116541775;9502.762430939281;9502.952485237529;9504.04959504044;4901.5588597026435;5229.032628963578;5243.082460569462;5253.947460525345;5266.657667251617;4283.657307415399;4432.357308190294;4442.42248507692;4742;4803.622207007554;3617.8010209438207;3707.962920370762;3711.536057992751;3798.088458769875;3913.8630147944687;1248.825164476974;1286.4468278640275;1291.9806202906964;1300.4148133340034;1304.62165971869;888.9022207557164;906.1844340093968;913.6847787513298;945.9830682016722;950.4154944708954;5511.311086114236;5631.831045068655;5743.770249190069;5767.779404929132;5936.37668044849;891.9554022298859;902.3999040637902;919.5356345045709;1019.4240254256322;1072.6353039966448;3511.279473058234;3575.785452872784;3706.1661126246377;3893.065664240562;4008.398740188995;8446.239838414573;9837.901620983841;10020.24848136378;10034.197264218845;10071.24465665078;9986.550605222783;9992.550335234931;9994.95053019437;10011.399316040915;10012.749681257881;6481.578697384655;7365.2634736525915;7644.655990480442;7758.185390533913;7863.331616812635;5488.588355873246;5549.694766787866;5564.137558678412;5643.492085712305;5906.881862362643;8865.379423440298;8913.688020919237;8948.015718270855;9123.042080581443;9225.58484868169;9067.183953444222;9240.353175277934;9261.981182069832;9344.859827102462;9363.297752668714;7118.715251390094;7267.854642907218;7267.963660181666;7268.164161121539;7278.563286202845;7481.064866891638;7673.731419400518;7730.226977302224;7748.186440423674;7906.52560846351;6752.155980502437;6933;7036.824079398056;7073.540219885691;7219.350258476552;6934.4452443805385;6953.65231738401;6957.060796792571;7024.719011239493;7025.894611580728;6287.559870809182;6324.588901721373;6400.263969643551;6400.839979000562;6416.743330266924;4843.564079232886;4944.48082951285;4978.203487442137;5013.674111182761;5146.511081447256;4900.068986892479;4906.411230652202;4981.8826998524755;4996.100701873697;5058.2918391425965 17:26:52 INFO - PROCESS | 1639 | |4;prototype.html;317.17218060861154;351.8539805980518;365.5448966037284;367.09511055248805;369.89458004468713;4161.438205842211;4440.311751678491;4877.463479017305;4878.8780280493;5179.611529135311;4968.30443737877;5044.873878153048;5056.797728090872;5058.494150584949;5081.568066714324;4081.7346872453195;4095.017195872984;4138.461999940004;4141.855035073773;4146.917061658765;3553.6851364994986;3620.7646502977223;3622.601513833476;3906;4032.8386864525382;4075.1849630073957;4090.6727461803134;4091.6628373923586;4096.344584866422;4097.344424892018;3744.9438258426144;4078.6737061035046;4081.6938729595104;4085.5097388313297;4090.202410529933;3212.3093534889877;3603.207294395229;3614.6264419520676;3632.5822530409077;3633.182533929871;4123.546409894909;4590.495045544987;5029.119904016782;5112.46319136489;5179.456157103526;4276.807543660543;4447.666425018104;4784.784684689197;4801.495842936476;4827.51724827518;5090.389153301628;5352.564583114712;5514.641548299348;5524.779008839641;5613.849160922042;3657.359962006665;3907.4529565860803;4060.6954478414236;4063.939040914359;4093.8771836844944;5535.920495503378;5785.528682790335;5789.45000224978;5843.737031833578;5851.385604511507;4028.9194216115516;4165.8541951032175;4171.643021692734;4184.372344148342;4195.181939521825;3655.8720444784835;3861.6331448512346;4253.298205796009;4264.799172814775;4278.572142785759;4723.913499895043;4739.099571081483;4758.692034363331;4824.710517368969;4851.490593487633;4935.925961110586;4976.776045077981;5598.356189038232;5627;5665.858353541194;1111.972200694973;1205.7166565857017;1317.0583033131356;1349.8515163332222;1394.842280906845;963.9132478077007;973.0075323170327;977.6285011695652;977.6871401151564;994.9104580587637;4408.55914408563;4884.849272219399;4900.877478063006;4934.851954441372;4958.017192721292;4278.507971583245;5243.528082472596;5406.270153529302;5408.891822163535;5415.268938693304;3922.195949830278;4407.493138289074;4611.884702882455;4858.58702010326;4871.732054737024;2643.828151170168;2772.5841123831588;2781.860906954644;2830.6461692288462;2837.645294338208;5023.799048038038;5290.603204759658;5320.52115309624;5556.388797232301;5630.943690563124;4867.3915760529935;5428.891422171534;5519.577861725756;5520.91718624221;5540.335159780771;4856.635752318591;4952.727599982036;5261.5264626183825;5371.811986580451;5543.861403464945;4253.872383828366;4570.314452831969;4906.18471165671;4977.17670325727;5115.2071428928575;4530.139273538017;4708.788104535236;4794.784234709517;5037.546620804145;5337.386200586982;4707.835225767081;4843.951560484491;5409.5672346213;5590.748416321357;5628.296462942132;3675.4119340905327;3747.437884317374;3930.960690393174;3937.6849852011196;3952.7233093684317;4649;4750.548697873696;4914.969724825752;5282.603804714585;5326.866828329323;4540.682152249443;4772.806798300425;4912.95087049125;5172.948270517397;5186.792528298825;4734.573888349927;4784.712917224978;5067.974660126676;5141.485851414828;5182.481751824938;4057.782665200487;4356.869293921188;4575.3365761965;4803.495632958628;4809.965857340689;4400.229959756965;4696.483386827515;4919.680220785637;5488.545535433193;5500.367457742251;5357.107162132561;5360.437154098763;5378.40837507866;5438.347398312228;5444.5916556259235 17:26:52 INFO - PROCESS | 1639 | |5;yui.html;713.0801266366387;750.5121670913906;763.7823220382193;778.6690656470998;840.6679361652148;15047.849521504782;15284.235788210586;15943;15993.440229591966;16047.919760401212;14193.574192774207;14418.5674429767;14578.271086445662;14618.049826761253;14644.560663180122;850.1498501498502;865.8441480533502;868.7524055644143;869.543489667923;872.8167084912176;687.9896801547981;707.2220557386885;711.4948386645463;861.0915484164204;862;743.7099531182843;746.220199891115;747.9775606731806;880.3837313880277;927.14702473724;919.56320747645;920.0477505781517;921.1249313152465;921.4885738415177;925.0887875442684;682.1984168601922;684.6131935456451;684.6782012454139;685.0204207982583;685.324954919404;10221.591136354537;12438.626841194779;14776.03955742884;15259.008164469275;15350.76973845382;13554.186748795006;14085.859141408557;14123;14168.291585420688;14215.85784142166;12924.935375323157;13183.868161318456;13214.603561893158;13282;13645.863541364657;4507.954920450787;4559.202139625552;4575.611073058794;4643.8142474300985;4725.0077483728455;90652;102974.48512757463;106454.46772766087;107414.92585074206;107869;10603.89396106029;11019.889801101886;11066.446677666085;11221.551137954553;11332.320060796379;21978.89010554937;22503.437414064778;22522.549549008927;22536.887315563315;22576.548469030527;6754.729810807611;7733.690652373954;7752.651130699132;7882;7967.28294453502;4163.91672166555;4229.788510574459;4256.553061928453;4275.46556680415;4313.12379115086;3477.94783078254;3563.3407819553468;3575.4100573405617;3587.695045921074;3591.8326543873345;641.6118248459708;674.150570281449;674.4739103499278;684.3567046975827;685.2633419074515;500.6120256801008;511.9180931051014;514.3570536828964;528.7911275046336;549.7581064331681;9491.810163796685;9773.853392199122;9888.802223955625;9998.500074996222;10107.29248952581;628.5505863307757;663.7942237906265;672.8553361027402;677.2110491277606;685.6914388525183;4533.433320834895;4734.976325118352;4752.714837109785;4763.7618119094595;4773.403324584427;103667;105054;105439.41840872393;106421;107996.46001769941;74099;75273;82020;82201;87556;68530;69038.65480672565;74019;74511;75114.8732769009;9437.716868493957;10334.948325258325;10653.8934610656;10682.305650132717;11044.723881903017;44154.337684935395;45769.93061526592;47543.76228118837;48006.039879202915;49836.01967763891;59841.401585983585;63902.68048659913;64331.67834160986;65477;66635.00047499388;11136.55453781872;11832.34922079294;11924.165308428674;12016.819747703967;12513.249205047727;14323.427062917783;14455.344339084326;14462.589974580515;14528.346224419714;14569.854301457273;13579.287251853591;13707.109037912503;13805.24071176095;13863.792043119563;14186.306176975098;13246.801297980346;13428.462861485432;13450.394732237268;13575.049746518043;13769.173849569059;11979.86191311824;13391.866081339062;13425.731485370434;13569.389377477839;13677.316134193052;10599.841002384816;11085.224034317844;11395.14536409756;11509.849015098423;11811.468483918072;8540.829183416421;9675.371100878421;10849.620263290746;10966.890331096905;11063.557457701601 17:26:52 INFO - PROCESS | 1639 | __end_tp_report 17:26:52 INFO - PROCESS | 1639 | __start_cc_report 17:26:52 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,3045 17:26:52 INFO - PROCESS | 1639 | __end_cc_report 17:26:52 INFO - PROCESS | 1639 | __startTimestamp1486085212191__endTimestamp 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | ------- Summary: start ------- 17:26:52 INFO - PROCESS | 1639 | Number of tests: 6 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5763.83 Median:5975.52 stddev:643.56 (10.8%) stddev-sans-first:612.28 17:26:52 INFO - PROCESS | 1639 | Values: 3160.9 3353.8 3386.7 3398.7 3454.5 4835.7 5604.9 5626.3 5636.4 5648.8 5676.4 5709.4 5780.7 5785.8 5830.9 5111.9 5124.2 5161.3 5220.3 5258.7 5275.7 5302.1 5310.0 5315.3 5318.0 5122.9 5279.2 5309.1 5341.3 5351.3 5154.9 5155.5 5203.9 5218.8 5250.6 5219.1 5223.4 5261.7 5269.3 5279.9 5719.3 5734.4 5745.4 5756.1 5782.2 6136.2 6142.2 6271.1 6281.6 6286.1 5744.8 5832.5 6203.6 6218.9 6259.3 5834.1 5886.0 5903.8 5919.8 5922.7 6232.6 6325.4 6333.8 6336.9 6338.2 6095.3 6097.5 6110.2 6139.0 6180.3 5312.8 6152.4 6180.0 6311.3 6314.7 6166.5 6177.4 6177.6 6180.6 6223.4 6188.0 6295.7 6303.4 6304.6 6313.9 4448.9 4892.8 4960.1 5059.5 5079.2 4445.5 4465.0 4476.9 4479.6 4524.1 5923.8 5942.4 5954.4 5970.0 5975.5 6182.7 6188.0 6213.0 6224.1 6226.2 5809.9 5816.7 5895.1 5951.7 5958.3 5519.6 5538.6 5550.6 5562.9 5567.5 6497.9 6524.2 6532.9 6536.1 6541.9 6037.1 6102.4 6225.9 6246.4 6265.6 6354.7 6399.9 6421.3 6441.1 6496.4 6146.6 6154.6 6155.2 6170.4 6183.4 6150.1 6158.6 6173.3 6191.9 6203.5 5721.2 6037.3 6055.8 6077.9 6106.7 6118.4 6122.8 6130.7 6131.2 6141.7 5724.3 5888.1 5912.2 5916.3 6067.5 6030.7 6086.6 6102.6 6108.6 6110.7 5995.9 5998.0 6004.7 6010.8 6018.4 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:69517.10 Median:39768.40 stddev:145097.53 (364.9%) stddev-sans-first:145412.02 17:26:52 INFO - PROCESS | 1639 | Values: 496.8 498.9 501.9 507.2 512.5 39738.0 42960.0 43441.7 45337.8 45426.0 43349.8 43771.0 44050.1 44140.6 44261.8 8418.6 8638.6 9236.6 9547.6 9569.5 10006.0 11088.0 11444.6 11449.0 11600.7 11367.4 11396.1 11460.7 11474.3 11540.4 8342.3 8537.0 11301.2 11471.8 11567.7 11237.8 11443.0 11457.7 11459.2 11505.1 42580.6 42764.0 42828.6 42834.8 42948.0 3641.5 4047.2 4074.7 4100.0 4103.7 29024.9 31082.0 31130.5 31162.8 31182.4 3146.9 3266.7 3295.8 3332.5 3339.3 481648.0 484143.0 484154.0 485575.0 488230.0 65488.1 66765.3 66771.3 66856.7 66862.3 67455.0 67629.7 70134.6 70207.6 70265.0 16177.8 16746.2 16755.4 16773.1 16783.2 11451.6 11691.1 11714.3 12505.7 12628.6 18395.0 18769.9 18956.5 18964.7 19133.9 6537.8 6619.8 6656.1 6660.2 6673.9 867.6 870.0 883.0 902.1 909.7 25872.5 30535.6 30597.7 30707.7 30753.4 1059.9 1079.8 1097.4 1101.8 1170.5 15344.5 15954.3 16005.8 16045.7 16256.3 606242.0 608862.0 611932.0 612926.0 614059.0 64229.0 64578.7 64672.4 64740.0 64792.0 1412.6 1447.3 1457.2 1462.0 1472.0 28867.6 29724.6 30359.0 31336.2 31511.0 53441.0 54071.7 54185.2 54206.5 54243.7 519347.0 526775.0 536826.0 537098.0 538742.0 35451.8 36528.0 41451.0 42088.0 42777.0 48630.5 48832.0 49750.0 49839.0 50488.7 35052.0 36989.0 39798.8 40169.4 40720.8 39020.2 39209.2 39845.0 40491.0 40745.0 37336.8 38221.9 38398.4 38472.2 38696.8 30838.4 34885.5 34921.8 35037.1 35195.8 25362.6 25379.1 25398.1 25808.9 25991.4 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:33259.93 Median:22323.33 stddev:81980.05 (367.2%) stddev-sans-first:82172.75 17:26:52 INFO - PROCESS | 1639 | Values: 334.6 376.0 392.8 401.6 427.0 15519.9 16170.8 18951.5 22356.3 22610.4 18220.0 18318.9 22119.4 22130.1 22290.3 5692.7 5698.5 5914.1 5985.3 6009.4 5376.9 5860.9 5899.3 5922.8 5979.0 4165.7 4822.8 5918.1 6422.0 6454.9 6326.8 6516.6 6542.3 6544.6 6551.6 5742.4 5761.4 5773.2 5784.3 5790.4 23124.5 26531.1 27698.6 27826.3 27899.4 2421.3 2712.4 2746.2 2758.9 2765.2 16010.8 16206.8 16351.6 16591.6 16957.7 1844.3 1855.7 1861.4 1870.1 1927.5 129338.4 131284.0 131341.7 132809.0 133414.3 11289.3 11421.4 11518.1 11548.2 11614.2 14831.8 15018.5 15057.8 15067.0 15097.0 8451.7 8509.3 8600.3 8773.3 8890.6 8629.1 8640.7 8653.9 8819.6 8851.0 6033.3 7318.8 7359.6 7393.7 7410.2 1533.7 1627.7 1929.5 1931.5 1940.3 1036.9 1043.5 1043.8 1066.7 1110.8 22654.9 23059.3 23106.0 23119.3 23166.3 1072.1 1083.0 1086.9 1112.4 1138.4 9371.6 11165.6 11450.3 12017.9 12382.8 405409.0 406937.0 407055.0 477955.0 632258.0 131038.0 140407.0 153828.5 158733.2 160087.6 1037.6 1047.8 1081.8 1089.3 1092.9 19673.0 20080.5 20086.1 20174.2 20199.0 57392.1 58413.0 59353.1 59854.0 60050.0 89166.0 89929.6 90069.0 90966.5 91446.0 6116.4 7525.4 7691.3 7886.7 7980.8 12790.7 13131.1 13284.3 13312.5 13760.1 11427.4 11538.9 11800.4 11881.3 11904.6 9505.5 11148.5 11420.9 11578.1 11804.6 11385.8 11493.5 11874.5 11906.6 11974.4 7195.8 7250.7 7573.2 7849.1 8074.4 10549.8 10782.5 11480.7 11844.7 11878.3 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5396.57 Median:5207.34 stddev:2915.36 (56.0%) stddev-sans-first:2908.13 17:26:52 INFO - PROCESS | 1639 | Values: 1408.4 1409.1 1435.1 1439.1 1446.3 8020.5 8425.0 8427.8 8436.4 8437.5 7475.7 7680.4 7683.3 8029.0 8265.6 2224.9 2308.5 2312.5 2313.1 2315.1 1951.3 2260.0 2304.7 2309.5 2317.9 2023.3 2095.8 2205.0 2248.1 2255.4 2249.5 2304.1 2305.8 2310.3 2313.5 2271.5 2315.7 2316.8 2337.4 2338.6 8329.5 8366.6 8375.5 8393.6 8401.0 5034.0 5197.4 5203.8 5210.9 5216.8 3317.5 3514.5 3527.5 3589.9 3601.5 1164.3 1171.3 1175.4 1214.5 1281.9 10302.3 10541.2 10553.8 10590.3 10746.0 4633.1 4720.1 4736.6 4739.0 4754.3 9488.0 9498.3 9502.8 9503.0 9504.0 4901.6 5229.0 5243.1 5253.9 5266.7 4283.7 4432.4 4442.4 4742.0 4803.6 3617.8 3708.0 3711.5 3798.1 3913.9 1248.8 1286.4 1292.0 1300.4 1304.6 888.9 906.2 913.7 946.0 950.4 5511.3 5631.8 5743.8 5767.8 5936.4 892.0 902.4 919.5 1019.4 1072.6 3511.3 3575.8 3706.2 3893.1 4008.4 8446.2 9837.9 10020.2 10034.2 10071.2 9986.6 9992.6 9995.0 10011.4 10012.7 6481.6 7365.3 7644.7 7758.2 7863.3 5488.6 5549.7 5564.1 5643.5 5906.9 8865.4 8913.7 8948.0 9123.0 9225.6 9067.2 9240.4 9262.0 9344.9 9363.3 7118.7 7267.9 7268.0 7268.2 7278.6 7481.1 7673.7 7730.2 7748.2 7906.5 6752.2 6933.0 7036.8 7073.5 7219.4 6934.4 6953.7 6957.1 7024.7 7025.9 6287.6 6324.6 6400.3 6400.8 6416.7 4843.6 4944.5 4978.2 5013.7 5146.5 4900.1 4906.4 4981.9 4996.1 5058.3 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4348.35 Median:4789.78 stddev:1249.64 (26.1%) stddev-sans-first:1215.97 17:26:52 INFO - PROCESS | 1639 | Values: 317.2 351.9 365.5 367.1 369.9 4161.4 4440.3 4877.5 4878.9 5179.6 4968.3 5044.9 5056.8 5058.5 5081.6 4081.7 4095.0 4138.5 4141.9 4146.9 3553.7 3620.8 3622.6 3906.0 4032.8 4075.2 4090.7 4091.7 4096.3 4097.3 3744.9 4078.7 4081.7 4085.5 4090.2 3212.3 3603.2 3614.6 3632.6 3633.2 4123.5 4590.5 5029.1 5112.5 5179.5 4276.8 4447.7 4784.8 4801.5 4827.5 5090.4 5352.6 5514.6 5524.8 5613.8 3657.4 3907.5 4060.7 4063.9 4093.9 5535.9 5785.5 5789.5 5843.7 5851.4 4028.9 4165.9 4171.6 4184.4 4195.2 3655.9 3861.6 4253.3 4264.8 4278.6 4723.9 4739.1 4758.7 4824.7 4851.5 4935.9 4976.8 5598.4 5627.0 5665.9 1112.0 1205.7 1317.1 1349.9 1394.8 963.9 973.0 977.6 977.7 994.9 4408.6 4884.8 4900.9 4934.9 4958.0 4278.5 5243.5 5406.3 5408.9 5415.3 3922.2 4407.5 4611.9 4858.6 4871.7 2643.8 2772.6 2781.9 2830.6 2837.6 5023.8 5290.6 5320.5 5556.4 5630.9 4867.4 5428.9 5519.6 5520.9 5540.3 4856.6 4952.7 5261.5 5371.8 5543.9 4253.9 4570.3 4906.2 4977.2 5115.2 4530.1 4708.8 4794.8 5037.5 5337.4 4707.8 4844.0 5409.6 5590.7 5628.3 3675.4 3747.4 3931.0 3937.7 3952.7 4649.0 4750.5 4915.0 5282.6 5326.9 4540.7 4772.8 4913.0 5172.9 5186.8 4734.6 4784.7 5068.0 5141.5 5182.5 4057.8 4356.9 4575.3 4803.5 4810.0 4400.2 4696.5 4919.7 5488.5 5500.4 5357.1 5360.4 5378.4 5438.3 5444.6 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19915.43 Median:4294.29 stddev:28311.86 (659.3%) stddev-sans-first:28354.56 17:26:52 INFO - PROCESS | 1639 | Values: 713.1 750.5 763.8 778.7 840.7 15047.8 15284.2 15943.0 15993.4 16047.9 14193.6 14418.6 14578.3 14618.0 14644.6 850.1 865.8 868.8 869.5 872.8 688.0 707.2 711.5 861.1 862.0 743.7 746.2 748.0 880.4 927.1 919.6 920.0 921.1 921.5 925.1 682.2 684.6 684.7 685.0 685.3 10221.6 12438.6 14776.0 15259.0 15350.8 13554.2 14085.9 14123.0 14168.3 14215.9 12924.9 13183.9 13214.6 13282.0 13645.9 4508.0 4559.2 4575.6 4643.8 4725.0 90652.0 102974.5 106454.5 107414.9 107869.0 10603.9 11019.9 11066.4 11221.6 11332.3 21978.9 22503.4 22522.5 22536.9 22576.5 6754.7 7733.7 7752.7 7882.0 7967.3 4163.9 4229.8 4256.6 4275.5 4313.1 3477.9 3563.3 3575.4 3587.7 3591.8 641.6 674.2 674.5 684.4 685.3 500.6 511.9 514.4 528.8 549.8 9491.8 9773.9 9888.8 9998.5 10107.3 628.6 663.8 672.9 677.2 685.7 4533.4 4735.0 4752.7 4763.8 4773.4 103667.0 105054.0 105439.4 106421.0 107996.5 74099.0 75273.0 82020.0 82201.0 87556.0 68530.0 69038.7 74019.0 74511.0 75114.9 9437.7 10334.9 10653.9 10682.3 11044.7 44154.3 45769.9 47543.8 48006.0 49836.0 59841.4 63902.7 64331.7 65477.0 66635.0 11136.6 11832.3 11924.2 12016.8 12513.2 14323.4 14455.3 14462.6 14528.3 14569.9 13579.3 13707.1 13805.2 13863.8 14186.3 13246.8 13428.5 13450.4 13575.0 13769.2 11979.9 13391.9 13425.7 13569.4 13677.3 10599.8 11085.2 11395.1 11509.8 11811.5 8540.8 9675.4 10849.6 10966.9 11063.6 17:26:52 INFO - PROCESS | 1639 | -------- Summary: end -------- 17:26:52 INFO - PROCESS | 1639 | 17:26:52 INFO - TEST-INFO | 1639: exit 0 17:26:52 INFO - TEST-OK | dromaeo_css | took 1120022ms 17:26:52 INFO - TEST-START | kraken 17:26:52 INFO - operating with platform_type : mac_ 17:26:52 INFO - Initialising browser for kraken test... 17:26:52 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpNbf7jL/profile http://localhost:49241/getInfo.html) 17:26:54 INFO - PROCESS | 1649 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 17:26:55 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 17:26:55 INFO - PROCESS | 1649 | colorDepth:24 17:26:55 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 17:26:55 INFO - PROCESS | 1649 | __metrics 17:26:55 INFO - PROCESS | 1649 | 1486085215284 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:26:55 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:26:55 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:26:56 INFO - TEST-INFO | 1649: exit 0 17:26:56 INFO - Browser initialized. 17:26:56 INFO - Running cycle 1/1 for kraken test... 17:26:56 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpNbf7jL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:27:08 INFO - PROCESS | 1650 | RSS: Main: 228540416 17:27:08 INFO - PROCESS | 1650 | 17:27:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 17:27:11 INFO - PROCESS | 1650 | RSS: Main: 288837632 17:27:11 INFO - PROCESS | 1650 | 17:27:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 17:27:15 INFO - PROCESS | 1650 | RSS: Main: 291815424 17:27:15 INFO - PROCESS | 1650 | 17:27:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 17:27:18 INFO - PROCESS | 1650 | RSS: Main: 380829696 17:27:18 INFO - PROCESS | 1650 | 17:27:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 17:27:21 INFO - PROCESS | 1650 | RSS: Main: 283131904 17:27:21 INFO - PROCESS | 1650 | 17:27:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:27:24 INFO - PROCESS | 1650 | RSS: Main: 287850496 17:27:24 INFO - PROCESS | 1650 | 17:27:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 17:27:27 INFO - PROCESS | 1650 | RSS: Main: 329859072 17:27:27 INFO - PROCESS | 1650 | 17:27:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 17:27:31 INFO - PROCESS | 1650 | RSS: Main: 332546048 17:27:31 INFO - PROCESS | 1650 | 17:27:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 17:27:34 INFO - PROCESS | 1650 | RSS: Main: 329990144 17:27:34 INFO - PROCESS | 1650 | 17:27:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:27:37 INFO - PROCESS | 1650 | RSS: Main: 305602560 17:27:37 INFO - PROCESS | 1650 | 17:27:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:27:39 INFO - PROCESS | 1650 | RSS: Main: 308555776 17:27:39 INFO - PROCESS | 1650 | 17:27:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:27:42 INFO - PROCESS | 1650 | RSS: Main: 310472704 17:27:42 INFO - PROCESS | 1650 | 17:27:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:27:46 INFO - PROCESS | 1650 | RSS: Main: 329072640 17:27:46 INFO - PROCESS | 1650 | 17:27:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:27:49 INFO - PROCESS | 1650 | RSS: Main: 323629056 17:27:49 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 17:27:51 INFO - PROCESS | 1650 | RSS: Main: 368685056 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | __start_tp_report 17:27:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 17:27:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 17:27:51 INFO - PROCESS | 1650 | |i|pagename|runs| 17:27:51 INFO - PROCESS | 1650 | |0;ai-astar;112;112;116;114;114;114;114;114;114;114 17:27:51 INFO - PROCESS | 1650 | |1;audio-beat-detection;133;104;107;144;109;109;108;108;129;109 17:27:51 INFO - PROCESS | 1650 | |2;audio-dft;182;187;183;186;184;185;178;181;183;184 17:27:51 INFO - PROCESS | 1650 | |3;audio-fft;79;57;50;99;64;81;80;81;95;71 17:27:51 INFO - PROCESS | 1650 | |4;audio-oscillator;90;70;86;69;68;69;83;68;70;82 17:27:51 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;89;87;91;87;89;86;90;88;90;86 17:27:51 INFO - PROCESS | 1650 | |6;imaging-darkroom;151;126;100;100;99;99;99;100;99;99 17:27:51 INFO - PROCESS | 1650 | |7;imaging-desaturate;88;88;85;85;87;87;85;88;88;84 17:27:51 INFO - PROCESS | 1650 | |8;json-parse-financial;61;56;57;57;58;57;59;85;86;85 17:27:51 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;64;71;72;76;76;71;70;70;71;75 17:27:51 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;88;86;86;86;85;88;86;84;85;90 17:27:51 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;141;139;137;139;141;150;142;137;178;152 17:27:51 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;174;174;174;174;173;172;173;171;171;174 17:27:51 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;68;68;67;69;68;66;69;65;66 17:27:51 INFO - PROCESS | 1650 | __end_tp_report 17:27:51 INFO - PROCESS | 1650 | __start_cc_report 17:27:51 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2065 17:27:51 INFO - PROCESS | 1650 | __end_cc_report 17:27:51 INFO - PROCESS | 1650 | __startTimestamp1486085271617__endTimestamp 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | ------- Summary: start ------- 17:27:51 INFO - PROCESS | 1650 | Number of tests: 14 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.14 (1.0%) stddev-sans-first:1.00 17:27:51 INFO - PROCESS | 1650 | Values: 112.0 112.0 116.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:116.00 Median:109.00 stddev:13.91 (12.8%) stddev-sans-first:13.33 17:27:51 INFO - PROCESS | 1650 | Values: 133.0 104.0 107.0 144.0 109.0 109.0 108.0 108.0 129.0 109.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.58 (1.4%) stddev-sans-first:2.70 17:27:51 INFO - PROCESS | 1650 | Values: 182.0 187.0 183.0 186.0 184.0 185.0 178.0 181.0 183.0 184.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:75.70 Median:80.50 stddev:15.53 (19.3%) stddev-sans-first:16.42 17:27:51 INFO - PROCESS | 1650 | Values: 79.0 57.0 50.0 99.0 64.0 81.0 80.0 81.0 95.0 71.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:8.67 (11.4%) stddev-sans-first:7.44 17:27:51 INFO - PROCESS | 1650 | Values: 90.0 70.0 86.0 69.0 68.0 69.0 83.0 68.0 70.0 82.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.77 (2.0%) stddev-sans-first:1.86 17:27:51 INFO - PROCESS | 1650 | Values: 89.0 87.0 91.0 87.0 89.0 86.0 90.0 88.0 90.0 86.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:107.20 Median:99.00 stddev:17.52 (17.7%) stddev-sans-first:8.89 17:27:51 INFO - PROCESS | 1650 | Values: 151.0 126.0 100.0 100.0 99.0 99.0 99.0 100.0 99.0 99.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:87.50 stddev:1.58 (1.8%) stddev-sans-first:1.58 17:27:51 INFO - PROCESS | 1650 | Values: 88.0 88.0 85.0 85.0 87.0 87.0 85.0 88.0 88.0 84.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:60.00 stddev:13.35 (22.2%) stddev-sans-first:14.03 17:27:51 INFO - PROCESS | 1650 | Values: 61.0 56.0 57.0 57.0 58.0 57.0 59.0 85.0 86.0 85.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:3.57 (5.0%) stddev-sans-first:2.51 17:27:51 INFO - PROCESS | 1650 | Values: 64.0 71.0 72.0 76.0 76.0 71.0 70.0 70.0 71.0 75.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.79 17:27:51 INFO - PROCESS | 1650 | Values: 88.0 86.0 86.0 86.0 85.0 88.0 86.0 84.0 85.0 90.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:141.50 stddev:12.47 (8.8%) stddev-sans-first:13.12 17:27:51 INFO - PROCESS | 1650 | Values: 141.0 139.0 137.0 139.0 141.0 150.0 142.0 137.0 178.0 152.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:174.00 stddev:1.25 (0.7%) stddev-sans-first:1.27 17:27:51 INFO - PROCESS | 1650 | Values: 174.0 174.0 174.0 174.0 173.0 172.0 173.0 171.0 171.0 174.0 17:27:51 INFO - PROCESS | 1650 | 17:27:51 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.41 17:27:51 INFO - PROCESS | 1650 | Values: 68.0 68.0 68.0 67.0 69.0 68.0 66.0 69.0 65.0 66.0 17:27:51 INFO - PROCESS | 1650 | -------- Summary: end -------- 17:27:51 INFO - PROCESS | 1650 | 17:27:52 INFO - TEST-INFO | 1650: exit 0 17:27:52 INFO - TEST-OK | kraken | took 59644ms 17:27:52 INFO - SUITE-END | took 1179s 17:27:52 INFO - Completed test suite (00:19:39) 17:27:52 INFO - javascript benchmark 17:27:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7340.298296947579, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.9209769755753, 3353.7987720736755, 3386.661333866612, 3398.6771256730603, 3454.516367708521, 4835.6943625221165, 5604.915926261109, 5626.324841019078, 5636.436356364362, 5648.774049038038, 5676.403977582357, 5709.371969083408, 5780.670445797469, 5785.82642520724, 5830.854228644275, 5111.897762044757, 5124.205748109049, 5161.251618515332, 5220.347456567929, 5258.658187217838, 5275.657082289658, 5302.125149350352, 5310.0441920454305, 5315.255864179018, 5318.042752304584, 5122.871928201787, 5279.155335146358, 5309.123994540896, 5341.252224688546, 5351.331083614548, 5154.943236636481, 5155.484451554815, 5203.869903252411, 5218.791248350061, 5250.579953603703, 5219.060569097561, 5223.37319521656, 5261.684298942076, 5269.341332333458, 5279.86800329991, 5719.313682358102, 5734.4265573442735, 5745.396733342981, 5756.079027355603, 5782.190493330937, 6136.20229370179, 6142.2225331421305, 6271.122042913996, 6281.623102613858, 6286.057091436275, 5744.827655170351, 5832.504237139849, 6203.627782333029, 6218.906716399258, 6259.311475737665, 5834.124881267801, 5885.999380105392, 5903.763849445974, 5919.763209471659, 5922.7038648067855, 6232.563720539519, 6325.399087086719, 6333.778317758856, 6336.922723137078, 6338.207724034496, 6095.299040610386, 6097.470219637845, 6110.16388879229, 6138.987067133873, 6180.289266734293, 5312.787488500417, 6152.415520525543, 6180.0, 6311.33730958252, 6314.652694101776, 6166.537509686791, 6177.382261773877, 6177.567570270128, 6180.598261113013, 6223.408776166257, 6187.969060154671, 6295.748170073146, 6303.432691057735, 6304.621722696653, 6313.873722525524, 4448.9110217796115, 4892.755362231874, 4960.057589058068, 5059.494050594984, 5079.187330027178, 4445.466544014675, 4464.99537604043, 4476.910461790746, 4479.641628669699, 4524.072565124142, 5923.7926672566255, 5942.37605051472, 5954.404559544011, 5969.970150149222, 5975.52195824333, 6182.721777520022, 6188.040853667689, 6213.0, 6224.097505861625, 6226.159468471789, 5809.941900581025, 5816.680082595498, 5895.056790913433, 5951.732172052268, 5958.344582095966, 5519.641223320471, 5538.584606154554, 5550.555955523549, 5562.916556251659, 5567.471090246501, 6497.870042599027, 6524.249711283168, 6532.934670653232, 6536.052272420377, 6541.8691626168165, 6037.09443583466, 6102.398363691312, 6225.906611400742, 6246.437820596168, 6265.592736472115, 6354.714037868214, 6399.93600064012, 6421.325760795236, 6441.066045423481, 6496.382843629847, 6146.569740118328, 6154.599951003171, 6155.199824022849, 6170.352113028068, 6183.412575805291, 6150.10823430609, 6158.599691020069, 6173.32093469709, 6191.907121393093, 6203.472704820141, 5721.170430287501, 6037.305709843425, 6055.757769689163, 6077.939220607737, 6106.664133472702, 6118.43644382056, 6122.794608433986, 6130.703893785035, 6131.193168498027, 6141.723622437091, 5724.255846739898, 5888.146218798164, 5912.231409916683, 5916.26046744157, 6067.54493412987, 6030.668313242813, 6086.573939824169, 6102.603330783485, 6108.633481991094, 6110.7250173741395, 5995.850103747442, 5998.0, 6004.699765011819, 6010.789622363196, 6018.3680713524445], "value": 5722.3315398885525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.8136948644258, 498.9301497790308, 501.89332521789436, 507.24927107880336, 512.4875124875124, 39738.00654983627, 42960.0, 43441.6967490975, 45337.77331113348, 45426.0, 43349.7832510837, 43771.0, 44050.05324281229, 44140.55859441413, 44261.77869110658, 8418.621162047692, 8638.568071596426, 9236.630534778618, 9547.570359333811, 9569.47367894769, 10006.0, 11088.0, 11444.599439019636, 11449.0, 11600.6519804406, 11367.374794386305, 11396.14528910335, 11460.656180314561, 11474.25417347874, 11540.365279909602, 8342.290905273, 8536.975562932426, 11301.152413569016, 11471.770564588704, 11567.669717982455, 11237.775244495146, 11443.0, 11457.713557161054, 11459.197856150073, 11505.079593632572, 42580.57419425828, 42764.0, 42828.57171428277, 42834.78582607098, 42948.0, 3641.5448068991377, 4047.150098479323, 4074.6943979201383, 4100.01599616093, 4103.651189648884, 29024.854875725487, 31082.0, 31130.533042004387, 31162.844185778926, 31182.376352472824, 3146.889858854952, 3266.738660907122, 3295.8187299698507, 3332.450145725952, 3339.3154403347257, 481648.0, 484143.0, 484154.0, 485574.99999999645, 488230.0, 65488.05303761342, 66765.321987972, 66771.31658934645, 66856.66571667111, 66862.33137668562, 67455.0, 67629.66185169043, 70134.64932675406, 70207.64896175487, 70265.0, 16177.838221617869, 16746.246418911178, 16755.41356052532, 16773.077480738433, 16783.2447539861, 11451.59919402817, 11691.123165762601, 11714.297142171497, 12505.74988500225, 12628.558000469939, 18394.98827564497, 18769.90615046916, 18956.526086847938, 18964.715529267072, 19133.85196888191, 6537.803865884031, 6619.834504137435, 6656.101426307483, 6660.200775906826, 6673.899891501631, 867.6009035843583, 869.9913000870037, 882.9646814127362, 902.1024081039408, 909.6984628888922, 25872.482550349265, 30535.625896834696, 30597.69402305993, 30707.692923070485, 30753.384932301677, 1059.86751656043, 1079.7840431913492, 1097.4403054442291, 1101.7631209290041, 1170.4849866058908, 15344.462943796914, 15954.28205730745, 16005.839941600669, 16045.679086418206, 16256.268467918972, 606242.0, 608862.0, 611932.0, 612926.0, 614059.0, 64229.0, 64578.677106615105, 64672.35327646758, 64740.0, 64792.0, 1412.639776856895, 1447.3269929482842, 1457.2131049233508, 1462.0423622527262, 1471.9990406523452, 28867.5669864948, 29724.554131688474, 30359.0, 31336.216594584406, 31511.0, 53441.0, 54071.72964135154, 54185.18722219091, 54206.45793542172, 54243.72878135584, 519347.0, 526775.0, 536826.0, 537098.0, 538742.0, 35451.82274088613, 36528.0, 41451.0, 42088.0, 42777.0, 48630.513694864014, 48832.0, 49750.0, 49839.00321993612, 50488.74755626198, 35052.0, 36989.0, 39798.801005994785, 40169.39745903872, 40720.79639601783, 39020.2195956085, 39209.215815684096, 39845.0, 40491.0, 40744.98137546585, 37336.81331593324, 38221.853344399715, 38398.424023640226, 38472.230555389295, 38696.80651596724, 30838.383232334778, 34885.476717848745, 34921.82539087288, 35037.124071898405, 35195.82402087973, 25362.619560706236, 25379.111731089324, 25398.111066112597, 25808.870955645103, 25991.350216244744], "value": 20816.046760263598, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.6202060661149, 376.0073406207614, 392.7702294157919, 401.6144501278772, 426.96370808481277, 15519.92240038801, 16170.75743863843, 18951.526211844695, 22356.329310120702, 22610.43473913151, 18220.0, 18318.908405457954, 22119.44701382466, 22130.114795408164, 22290.331290061284, 5692.658440493563, 5698.487136157755, 5914.1128830675525, 5985.281766188064, 6009.399060094, 5376.946230537684, 5860.882782344351, 5899.262592175978, 5922.792702255422, 5979.043353063511, 4165.708400411972, 4822.807087716487, 5918.112283157539, 6422.03669449584, 6454.93545064548, 6326.841828954267, 6516.641584712839, 6542.3130571290285, 6544.5746026508605, 6551.639659818684, 5742.425757424266, 5761.366249712528, 5773.220615216976, 5784.2769653793275, 5790.420957904218, 23124.53750924989, 26531.07141250047, 27698.5845212322, 27826.304342391602, 27899.442011159663, 2421.3462365161486, 2712.389712314733, 2746.231055304518, 2758.8758505867286, 2765.1842706401458, 16010.759838602431, 16206.83793162065, 16351.59121021972, 16591.585210369838, 16957.660846783117, 1844.2530775035998, 1855.701817496202, 1861.413654698779, 1870.064967516242, 1927.5373910261437, 129338.35330823285, 131284.0, 131341.73165366965, 132809.0, 133414.3329283357, 11289.266197697123, 11421.42892855354, 11518.136139789549, 11548.249363791494, 11614.187006909435, 14831.77752333716, 15018.549443516711, 15057.774133388008, 15067.0, 15097.0, 8451.746447606582, 8509.27671147947, 8600.311975042106, 8773.341999350074, 8890.64437422496, 8629.137086291446, 8640.654373824977, 8653.913460865311, 8819.647214111492, 8851.0, 6033.276006879115, 7318.817029574197, 7359.595222262827, 7393.667284972189, 7410.184879663233, 1533.693261347735, 1627.7070127377185, 1929.5176205948512, 1931.4591914264029, 1940.302235543899, 1036.8957060730297, 1043.5408420295046, 1043.8016776812533, 1066.6959916423937, 1110.8000559899135, 22654.886725566266, 23059.308220753403, 23106.0, 23119.306420807403, 23166.3050108497, 1072.0619457974271, 1083.0, 1086.8750093739163, 1112.4437781109445, 1138.3795831271977, 9371.578278977462, 11165.609203677832, 11450.255733377304, 12017.939910300567, 12382.81425778614, 405409.0, 406937.0, 407055.0, 477955.0, 632258.0, 131038.0, 140407.0, 153828.46171538366, 158733.2063339676, 160087.59430636972, 1037.5745944162857, 1047.8061558611835, 1081.799867024603, 1089.340948726025, 1092.9398883061508, 19673.0, 20080.49798755043, 20086.09612567409, 20174.19303227913, 20198.990050497123, 57392.1391179141, 58413.0, 59353.10970335362, 59854.0, 60050.0, 89166.0, 89929.55035224782, 90069.0, 90966.54516727374, 91446.0, 6116.449519543263, 7525.36034437079, 7691.30778229962, 7886.684532618631, 7980.760577182694, 12790.68023299425, 13131.146475479063, 13284.335783210607, 13312.46750129984, 13760.105593136414, 11427.371494567878, 11538.865309917692, 11800.350980696145, 11881.287122772661, 11904.583339583072, 9505.477198754135, 11148.498317575892, 11420.885791141982, 11578.07375409982, 11804.645860624196, 11385.829212561985, 11493.482793274155, 11874.46564904564, 11906.642800715992, 11974.401279935793, 7195.820104497429, 7250.709971601288, 7573.2048134945035, 7849.076976140882, 8074.434789564909, 10549.789004220027, 10782.514786834454, 11480.655580332603, 11844.70388240301, 11878.346690931736], "value": 10131.822618190134, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.42254675583, 1409.0770545292837, 1435.0913285330512, 1439.071798689845, 1446.3346860444194, 8020.5187688738715, 8425.0, 8427.789305267355, 8436.409451338408, 8437.535935523543, 7475.73834915778, 7680.385569154469, 7683.346915512174, 8029.036515618134, 8265.628046737897, 2224.92212772553, 2308.515211805523, 2312.4796920692793, 2313.1094528606523, 2315.085541211229, 1951.2585217617286, 2260.0394832196334, 2304.7119110111234, 2309.538092381522, 2317.9072837086583, 2023.2615095490187, 2095.779943105967, 2205.007746514067, 2248.123231939545, 2255.4023183856293, 2249.5275992041693, 2304.0783686525356, 2305.7578954209735, 2310.2722642367767, 2313.4679023824615, 2271.4548508357875, 2315.7452680205165, 2316.81465482761, 2337.4039619896816, 2338.649202619604, 8329.50022998622, 8366.623501942428, 8375.539345336005, 8393.580320983987, 8401.0, 5033.974830125862, 5197.402298735656, 5203.791848326063, 5210.895782084375, 5216.7913283469, 3317.5189597508465, 3514.5431093957877, 3527.5414196154593, 3589.9282014359565, 3601.549806274216, 1164.3072371938683, 1171.2679575265458, 1175.3770501634062, 1214.5061574263616, 1281.9294938778364, 10302.33034852732, 10541.156707463384, 10553.84169237462, 10590.311629744043, 10746.0, 4633.0733853229485, 4720.12677654635, 4736.573708366263, 4739.028499157665, 4754.263089221176, 9487.952560237292, 9498.335116541775, 9502.762430939281, 9502.952485237529, 9504.04959504044, 4901.5588597026435, 5229.032628963578, 5243.082460569462, 5253.947460525345, 5266.657667251617, 4283.657307415399, 4432.357308190294, 4442.42248507692, 4742.0, 4803.622207007554, 3617.8010209438207, 3707.962920370762, 3711.536057992751, 3798.088458769875, 3913.8630147944687, 1248.825164476974, 1286.4468278640275, 1291.9806202906964, 1300.4148133340034, 1304.62165971869, 888.9022207557164, 906.1844340093968, 913.6847787513298, 945.9830682016722, 950.4154944708954, 5511.311086114236, 5631.831045068655, 5743.770249190069, 5767.779404929132, 5936.37668044849, 891.9554022298859, 902.3999040637902, 919.5356345045709, 1019.4240254256322, 1072.6353039966448, 3511.279473058234, 3575.785452872784, 3706.1661126246377, 3893.065664240562, 4008.398740188995, 8446.239838414573, 9837.901620983841, 10020.24848136378, 10034.197264218845, 10071.24465665078, 9986.550605222783, 9992.550335234931, 9994.95053019437, 10011.399316040915, 10012.749681257881, 6481.578697384655, 7365.2634736525915, 7644.655990480442, 7758.185390533913, 7863.331616812635, 5488.588355873246, 5549.694766787866, 5564.137558678412, 5643.492085712305, 5906.881862362643, 8865.379423440298, 8913.688020919237, 8948.015718270855, 9123.042080581443, 9225.58484868169, 9067.183953444222, 9240.353175277934, 9261.981182069832, 9344.859827102462, 9363.297752668714, 7118.715251390094, 7267.854642907218, 7267.963660181666, 7268.164161121539, 7278.563286202845, 7481.064866891638, 7673.731419400518, 7730.226977302224, 7748.186440423674, 7906.52560846351, 6752.155980502437, 6933.0, 7036.824079398056, 7073.540219885691, 7219.350258476552, 6934.4452443805385, 6953.65231738401, 6957.060796792571, 7024.719011239493, 7025.894611580728, 6287.559870809182, 6324.588901721373, 6400.263969643551, 6400.839979000562, 6416.743330266924, 4843.564079232886, 4944.48082951285, 4978.203487442137, 5013.674111182761, 5146.511081447256, 4900.068986892479, 4906.411230652202, 4981.8826998524755, 4996.100701873697, 5058.2918391425965], "value": 4401.932520320797, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.17218060861154, 351.8539805980518, 365.5448966037284, 367.09511055248805, 369.89458004468713, 4161.438205842211, 4440.311751678491, 4877.463479017305, 4878.8780280493, 5179.611529135311, 4968.30443737877, 5044.873878153048, 5056.797728090872, 5058.494150584949, 5081.568066714324, 4081.7346872453195, 4095.017195872984, 4138.461999940004, 4141.855035073773, 4146.917061658765, 3553.6851364994986, 3620.7646502977223, 3622.601513833476, 3906.0, 4032.8386864525382, 4075.1849630073957, 4090.6727461803134, 4091.6628373923586, 4096.344584866422, 4097.344424892018, 3744.9438258426144, 4078.6737061035046, 4081.6938729595104, 4085.5097388313297, 4090.202410529933, 3212.3093534889877, 3603.207294395229, 3614.6264419520676, 3632.5822530409077, 3633.182533929871, 4123.546409894909, 4590.495045544987, 5029.119904016782, 5112.46319136489, 5179.456157103526, 4276.807543660543, 4447.666425018104, 4784.784684689197, 4801.495842936476, 4827.51724827518, 5090.389153301628, 5352.564583114712, 5514.641548299348, 5524.779008839641, 5613.849160922042, 3657.359962006665, 3907.4529565860803, 4060.6954478414236, 4063.939040914359, 4093.8771836844944, 5535.920495503378, 5785.528682790335, 5789.45000224978, 5843.737031833578, 5851.385604511507, 4028.9194216115516, 4165.8541951032175, 4171.643021692734, 4184.372344148342, 4195.181939521825, 3655.8720444784835, 3861.6331448512346, 4253.298205796009, 4264.799172814775, 4278.572142785759, 4723.913499895043, 4739.099571081483, 4758.692034363331, 4824.710517368969, 4851.490593487633, 4935.925961110586, 4976.776045077981, 5598.356189038232, 5627.0, 5665.858353541194, 1111.972200694973, 1205.7166565857017, 1317.0583033131356, 1349.8515163332222, 1394.842280906845, 963.9132478077007, 973.0075323170327, 977.6285011695652, 977.6871401151564, 994.9104580587637, 4408.55914408563, 4884.849272219399, 4900.877478063006, 4934.851954441372, 4958.017192721292, 4278.507971583245, 5243.528082472596, 5406.270153529302, 5408.891822163535, 5415.268938693304, 3922.195949830278, 4407.493138289074, 4611.884702882455, 4858.58702010326, 4871.732054737024, 2643.828151170168, 2772.5841123831588, 2781.860906954644, 2830.6461692288462, 2837.645294338208, 5023.799048038038, 5290.603204759658, 5320.52115309624, 5556.388797232301, 5630.943690563124, 4867.3915760529935, 5428.891422171534, 5519.577861725756, 5520.91718624221, 5540.335159780771, 4856.635752318591, 4952.727599982036, 5261.5264626183825, 5371.811986580451, 5543.861403464945, 4253.872383828366, 4570.314452831969, 4906.18471165671, 4977.17670325727, 5115.2071428928575, 4530.139273538017, 4708.788104535236, 4794.784234709517, 5037.546620804145, 5337.386200586982, 4707.835225767081, 4843.951560484491, 5409.5672346213, 5590.748416321357, 5628.296462942132, 3675.4119340905327, 3747.437884317374, 3930.960690393174, 3937.6849852011196, 3952.7233093684317, 4649.0, 4750.548697873696, 4914.969724825752, 5282.603804714585, 5326.866828329323, 4540.682152249443, 4772.806798300425, 4912.95087049125, 5172.948270517397, 5186.792528298825, 4734.573888349927, 4784.712917224978, 5067.974660126676, 5141.485851414828, 5182.481751824938, 4057.782665200487, 4356.869293921188, 4575.3365761965, 4803.495632958628, 4809.965857340689, 4400.229959756965, 4696.483386827515, 4919.680220785637, 5488.545535433193, 5500.367457742251, 5357.107162132561, 5360.437154098763, 5378.40837507866, 5438.347398312228, 5444.5916556259235], "value": 3971.7692104291414, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.0801266366387, 750.5121670913906, 763.7823220382193, 778.6690656470998, 840.6679361652148, 15047.849521504782, 15284.235788210586, 15943.0, 15993.440229591966, 16047.919760401212, 14193.574192774207, 14418.5674429767, 14578.271086445662, 14618.049826761253, 14644.560663180122, 850.1498501498502, 865.8441480533502, 868.7524055644143, 869.543489667923, 872.8167084912176, 687.9896801547981, 707.2220557386885, 711.4948386645463, 861.0915484164204, 862.0, 743.7099531182843, 746.220199891115, 747.9775606731806, 880.3837313880277, 927.14702473724, 919.56320747645, 920.0477505781517, 921.1249313152465, 921.4885738415177, 925.0887875442684, 682.1984168601922, 684.6131935456451, 684.6782012454139, 685.0204207982583, 685.324954919404, 10221.591136354537, 12438.626841194779, 14776.03955742884, 15259.008164469275, 15350.76973845382, 13554.186748795006, 14085.859141408557, 14123.0, 14168.291585420688, 14215.85784142166, 12924.935375323157, 13183.868161318456, 13214.603561893158, 13282.0, 13645.863541364657, 4507.954920450787, 4559.202139625552, 4575.611073058794, 4643.8142474300985, 4725.0077483728455, 90652.0, 102974.48512757463, 106454.46772766087, 107414.92585074206, 107869.0, 10603.89396106029, 11019.889801101886, 11066.446677666085, 11221.551137954553, 11332.320060796379, 21978.89010554937, 22503.437414064778, 22522.549549008927, 22536.887315563315, 22576.548469030527, 6754.729810807611, 7733.690652373954, 7752.651130699132, 7882.0, 7967.28294453502, 4163.91672166555, 4229.788510574459, 4256.553061928453, 4275.46556680415, 4313.12379115086, 3477.94783078254, 3563.3407819553468, 3575.4100573405617, 3587.695045921074, 3591.8326543873345, 641.6118248459708, 674.150570281449, 674.4739103499278, 684.3567046975827, 685.2633419074515, 500.6120256801008, 511.9180931051014, 514.3570536828964, 528.7911275046336, 549.7581064331681, 9491.810163796685, 9773.853392199122, 9888.802223955625, 9998.500074996222, 10107.29248952581, 628.5505863307757, 663.7942237906265, 672.8553361027402, 677.2110491277606, 685.6914388525183, 4533.433320834895, 4734.976325118352, 4752.714837109785, 4763.7618119094595, 4773.403324584427, 103667.0, 105054.0, 105439.41840872393, 106421.0, 107996.46001769941, 74099.0, 75273.0, 82020.0, 82201.0, 87556.0, 68530.0, 69038.65480672565, 74019.0, 74511.0, 75114.8732769009, 9437.716868493957, 10334.948325258325, 10653.8934610656, 10682.305650132717, 11044.723881903017, 44154.337684935395, 45769.93061526592, 47543.76228118837, 48006.039879202915, 49836.01967763891, 59841.401585983585, 63902.68048659913, 64331.67834160986, 65477.0, 66635.00047499388, 11136.55453781872, 11832.34922079294, 11924.165308428674, 12016.819747703967, 12513.249205047727, 14323.427062917783, 14455.344339084326, 14462.589974580515, 14528.346224419714, 14569.854301457273, 13579.287251853591, 13707.109037912503, 13805.24071176095, 13863.792043119563, 14186.306176975098, 13246.801297980346, 13428.462861485432, 13450.394732237268, 13575.049746518043, 13769.173849569059, 11979.86191311824, 13391.866081339062, 13425.731485370434, 13569.389377477839, 13677.316134193052, 10599.841002384816, 11085.224034317844, 11395.14536409756, 11509.849015098423, 11811.468483918072, 8540.829183416421, 9675.371100878421, 10849.620263290746, 10966.890331096905, 11063.557457701601], "value": 7412.160413545925, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 116.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 104.0, 107.0, 144.0, 109.0, 109.0, 108.0, 108.0, 129.0, 109.0], "value": 116.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 183.0, 186.0, 184.0, 185.0, 178.0, 181.0, 183.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 50.0, 99.0, 64.0, 81.0, 80.0, 81.0, 95.0, 71.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 69.0, 68.0, 69.0, 83.0, 68.0, 70.0, 82.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 87.0, 89.0, 86.0, 90.0, 88.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 126.0, 100.0, 100.0, 99.0, 99.0, 99.0, 100.0, 99.0, 99.0], "value": 107.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 85.0, 85.0, 87.0, 87.0, 85.0, 88.0, 88.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 56.0, 57.0, 57.0, 58.0, 57.0, 59.0, 85.0, 86.0, 85.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 72.0, 76.0, 76.0, 71.0, 70.0, 70.0, 71.0, 75.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 86.0, 85.0, 88.0, 86.0, 84.0, 85.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 137.0, 139.0, 141.0, 150.0, 142.0, 137.0, 178.0, 152.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 174.0, 174.0, 173.0, 172.0, 173.0, 171.0, 171.0, 174.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 69.0, 68.0, 66.0, 69.0, 65.0, 66.0], "value": 67.4, "unit": "score"}]}]} 17:27:52 INFO - Return code: 0 17:27:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:27:52 ERROR - # TBPL SUCCESS # 17:27:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:27:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:27:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:27:52 INFO - 2017-02-02 17:27:52.924 screenresolution[1654:9780] starting screenresolution argv=screenresolution get 17:27:52 INFO - 2017-02-02 17:27:52.927 screenresolution[1654:9780] Display 0: 1600x1200x32@60 17:27:52 INFO - 2017-02-02 17:27:52.934 screenresolution[1655:9783] starting screenresolution argv=screenresolution list 17:27:52 INFO - Available Modes on Display 0 17:27:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:27:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:27:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:27:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:27:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:27:53 INFO - Intel Iris: 17:27:53 INFO - Chipset Model: Intel Iris 17:27:53 INFO - Type: GPU 17:27:53 INFO - Bus: Built-In 17:27:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:27:53 INFO - Vendor: Intel (0x8086) 17:27:53 INFO - Device ID: 0x0a2e 17:27:53 INFO - Revision ID: 0x0009 17:27:53 INFO - Displays: 17:27:53 INFO - Display: 17:27:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:27:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:27:53 INFO - Display Serial Number: 1600x1200 60 17:27:53 INFO - Main Display: Yes 17:27:53 INFO - Mirror: Off 17:27:53 INFO - Online: Yes 17:27:53 INFO - Rotation: Supported 17:27:53 INFO - Return code: 0 17:27:53 INFO - Running post-action listener: _resource_record_post_action 17:27:53 INFO - [mozharness: 2017-02-03 01:27:53.169107Z] Finished run-tests step (success) 17:27:53 INFO - Running post-run listener: _resource_record_post_run 17:27:53 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37762048; Write bytes: 552403968; Read time: 240; Write time: 1623 17:27:53 INFO - TinderboxPrint: CPU usage
53.9% 17:27:53 INFO - TinderboxPrint: I/O read bytes / time
37,762,048 / 240 17:27:53 INFO - TinderboxPrint: I/O write bytes / time
552,403,968 / 1,623 17:27:53 INFO - TinderboxPrint: CPU idle
2,216.5 (46.1%) 17:27:53 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 17:27:53 INFO - TinderboxPrint: CPU user
1,633.0 (34.0%) 17:27:53 INFO - TinderboxPrint: Swap in / out
289,882,112 / 0 17:27:53 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246821376; Write bytes: 241698816; Read time: 17871; Write time: 493 17:27:53 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35668992; Write bytes: 295283712; Read time: 219; Write time: 1091 17:27:53 INFO - Running post-run listener: _upload_blobber_files 17:27:53 INFO - Blob upload gear active. 17:27:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:27:53 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 17:27:53 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'] 17:27:53 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 17:27:54 INFO - (blobuploader) - INFO - Open directory for files ... 17:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:55 INFO - (blobuploader) - INFO - Done attempting. 17:27:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:27:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:27:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:56 INFO - (blobuploader) - INFO - Done attempting. 17:27:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:27:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:27:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:57 INFO - (blobuploader) - INFO - Done attempting. 17:27:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:27:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:27:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:59 INFO - (blobuploader) - INFO - Done attempting. 17:27:59 INFO - (blobuploader) - INFO - Iteration through files over. 17:27:59 INFO - Return code: 0 17:27:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:27:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:27:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a17a0806c4397ccb6d480e6e18241b20bd85fc3d49f1e65c95e75461e46151c35c45a64c2f17c5ef3e6c5677a5044ff1c3a33f63da2b5259f4fbed1bf5fcd64d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcd276e66f891984c5201ce7c4b29cd42dfa897e7fac1c29972c3070a17020b4c036d855d240119634d2f5b38ed491ea656f355224feaf916dfd8c83fd84e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7a134a6f6edd4ddeb73d9995ae7f23df8bc5217a9f0e33539f229fc526b493b26be9f1205a0308281dc7112914f8217cd4e10e51bd1b3b5c46e28b351c1f2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/480442e71c9d5005dc6380703ce4c0aee551963a3ea9aeb403bc56f2cba7426ef970d40930f60b5fa0ba32062f3711dae60f78a64e6e73c76b5fccaeae4eb545"} 17:27:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:27:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:27:59 INFO - Contents: 17:27:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a17a0806c4397ccb6d480e6e18241b20bd85fc3d49f1e65c95e75461e46151c35c45a64c2f17c5ef3e6c5677a5044ff1c3a33f63da2b5259f4fbed1bf5fcd64d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcd276e66f891984c5201ce7c4b29cd42dfa897e7fac1c29972c3070a17020b4c036d855d240119634d2f5b38ed491ea656f355224feaf916dfd8c83fd84e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7a134a6f6edd4ddeb73d9995ae7f23df8bc5217a9f0e33539f229fc526b493b26be9f1205a0308281dc7112914f8217cd4e10e51bd1b3b5c46e28b351c1f2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/480442e71c9d5005dc6380703ce4c0aee551963a3ea9aeb403bc56f2cba7426ef970d40930f60b5fa0ba32062f3711dae60f78a64e6e73c76b5fccaeae4eb545"} 17:27:59 INFO - Running post-run listener: copy_logs_to_upload_dir 17:27:59 INFO - Copying logs to upload dir... 17:27:59 INFO - mkdir: /builds/slave/test/build/upload/logs 17:27:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.729779 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-02-02 17:27:59.684541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 17:27:59.688302) ========= 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.kGWeghRpsy/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.sN61uGbfRQ/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a17a0806c4397ccb6d480e6e18241b20bd85fc3d49f1e65c95e75461e46151c35c45a64c2f17c5ef3e6c5677a5044ff1c3a33f63da2b5259f4fbed1bf5fcd64d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcd276e66f891984c5201ce7c4b29cd42dfa897e7fac1c29972c3070a17020b4c036d855d240119634d2f5b38ed491ea656f355224feaf916dfd8c83fd84e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7a134a6f6edd4ddeb73d9995ae7f23df8bc5217a9f0e33539f229fc526b493b26be9f1205a0308281dc7112914f8217cd4e10e51bd1b3b5c46e28b351c1f2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/480442e71c9d5005dc6380703ce4c0aee551963a3ea9aeb403bc56f2cba7426ef970d40930f60b5fa0ba32062f3711dae60f78a64e6e73c76b5fccaeae4eb545"} build_url:https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010537 build_url: 'https://queue.taskcluster.net/v1/task/eE58sB96T_ap_mf7wk9IIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a17a0806c4397ccb6d480e6e18241b20bd85fc3d49f1e65c95e75461e46151c35c45a64c2f17c5ef3e6c5677a5044ff1c3a33f63da2b5259f4fbed1bf5fcd64d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebcd276e66f891984c5201ce7c4b29cd42dfa897e7fac1c29972c3070a17020b4c036d855d240119634d2f5b38ed491ea656f355224feaf916dfd8c83fd84e8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7a134a6f6edd4ddeb73d9995ae7f23df8bc5217a9f0e33539f229fc526b493b26be9f1205a0308281dc7112914f8217cd4e10e51bd1b3b5c46e28b351c1f2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/480442e71c9d5005dc6380703ce4c0aee551963a3ea9aeb403bc56f2cba7426ef970d40930f60b5fa0ba32062f3711dae60f78a64e6e73c76b5fccaeae4eb545"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 17:27:59.726764) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 17:27:59.727542) ========= 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.kGWeghRpsy/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.sN61uGbfRQ/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.005102 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 17:27:59.779489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 17:27:59.780246) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 17:27:59.780986) ========= ========= Total master_lag: 0.20 =========