builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0023 starttime: 1483821130.31 results: success (0) buildid: 20170107092756 builduid: 81eb1450b4864903923807d924e45274 revision: f7191cd859142678729a2abcdf340f97edc3f202 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.313015) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.313502) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.313780) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.340840) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.341223) ========= 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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-07 12:32:10-- 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% 20.9M=0.001s 2017-01-07 12:32:10 (20.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129737 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.495220) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.495569) ========= 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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.026838 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:10.545603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 12:32:10.546002) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f7191cd859142678729a2abcdf340f97edc3f202 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f7191cd859142678729a2abcdf340f97edc3f202 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DHghJfhyCH/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.Ma5IaW6N1h/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-07 12:32:10,637 truncating revision to first 12 chars 2017-01-07 12:32:10,637 Setting DEBUG logging. 2017-01-07 12:32:10,637 attempt 1/10 2017-01-07 12:32:10,637 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f7191cd85914?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 12:32:11,679 unpacking tar archive at: mozilla-beta-f7191cd85914/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331742 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 12:32:11.901084) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:11.901406) ========= script_repo_revision: f7191cd859142678729a2abcdf340f97edc3f202 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:11.901850) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:11.902124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 12:32:11.922581) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-07 12:32:11.923240) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DHghJfhyCH/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.Ma5IaW6N1h/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:12 INFO - MultiFileLogger online at 20170107 12:32:12 in /builds/slave/test 12:32:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:32:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:32:12 INFO - {'append_to_log': False, 12:32:12 INFO - 'base_work_dir': '/builds/slave/test', 12:32:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:32:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:32:12 INFO - 'branch': 'Mozilla-Beta', 12:32:12 INFO - 'buildbot_json_path': 'buildprops.json', 12:32:12 INFO - 'config_files': ('talos/mac_config.py',), 12:32:12 INFO - 'default_actions': ('clobber', 12:32:12 INFO - 'read-buildbot-config', 12:32:12 INFO - 'download-and-extract', 12:32:12 INFO - 'populate-webroot', 12:32:12 INFO - 'create-virtualenv', 12:32:12 INFO - 'install', 12:32:12 INFO - 'run-tests'), 12:32:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:32:12 INFO - 'download_minidump_stackwalk': True, 12:32:12 INFO - 'download_symbols': 'ondemand', 12:32:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:32:12 INFO - 'tooltool.py': '/tools/tooltool.py', 12:32:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:32:12 INFO - '/tools/misc-python/virtualenv.py')}, 12:32:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:32:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:32:12 INFO - 'installer_path': 'installer.exe', 12:32:12 INFO - 'log_level': 'info', 12:32:12 INFO - 'log_name': 'talos', 12:32:12 INFO - 'log_to_console': True, 12:32:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:32:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:32:12 INFO - 'opt_config_files': (), 12:32:12 INFO - 'pip_index': False, 12:32:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:32:12 INFO - 'cmd': ('bash', 12:32:12 INFO - '-c', 12:32:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:32:12 INFO - 'enabled': True, 12:32:12 INFO - 'halt_on_failure': False, 12:32:12 INFO - 'name': 'check_screen_resolution'},), 12:32:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:32:12 INFO - 'cmd': ('bash', 12:32:12 INFO - '-c', 12:32:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:32:12 INFO - 'enabled': True, 12:32:12 INFO - 'halt_on_failure': False, 12:32:12 INFO - 'name': 'check_screen_resolution'},), 12:32:12 INFO - 'run_cmd_checks_enabled': True, 12:32:12 INFO - 'sps_profile': False, 12:32:12 INFO - 'sps_profile_interval': 0, 12:32:12 INFO - 'suite': 'dromaeojs', 12:32:12 INFO - 'system_bits': '32', 12:32:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:32:12 INFO - 'title': 't-yosemite-r7-0023', 12:32:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:32:12 INFO - 'use_talos_json': True, 12:32:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:32:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:32:12 INFO - 'work_dir': 'build'} 12:32:12 INFO - [mozharness: 2017-01-07 20:32:12.121181Z] Running clobber step. 12:32:12 INFO - Running pre-action listener: _resource_record_pre_action 12:32:12 INFO - Running main action method: clobber 12:32:12 INFO - rmtree: /builds/slave/test/build 12:32:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:32:14 INFO - Running post-action listener: _resource_record_post_action 12:32:14 INFO - [mozharness: 2017-01-07 20:32:14.580415Z] Finished clobber step (success) 12:32:14 INFO - [mozharness: 2017-01-07 20:32:14.580534Z] Running read-buildbot-config step. 12:32:14 INFO - Running pre-action listener: _resource_record_pre_action 12:32:14 INFO - Running main action method: read_buildbot_config 12:32:14 INFO - Using buildbot properties: 12:32:14 INFO - { 12:32:14 INFO - "project": "", 12:32:14 INFO - "product": "firefox", 12:32:14 INFO - "script_repo_revision": "production", 12:32:14 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:32:14 INFO - "repository": "", 12:32:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:32:14 INFO - "buildid": "20170107092756", 12:32:14 INFO - "pgo_build": "False", 12:32:14 INFO - "basedir": "/builds/slave/test", 12:32:14 INFO - "buildnumber": 8, 12:32:14 INFO - "slavename": "t-yosemite-r7-0023", 12:32:14 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:32:14 INFO - "platform": "yosemite_r7", 12:32:14 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:32:14 INFO - "branch": "mozilla-beta", 12:32:14 INFO - "repo_path": "releases/mozilla-beta", 12:32:14 INFO - "revision": "f7191cd859142678729a2abcdf340f97edc3f202", 12:32:14 INFO - "stage_platform": "macosx64", 12:32:14 INFO - "builduid": "81eb1450b4864903923807d924e45274", 12:32:14 INFO - "slavebuilddir": "test" 12:32:14 INFO - } 12:32:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 12:32:14 INFO - Running post-action listener: _resource_record_post_action 12:32:14 INFO - [mozharness: 2017-01-07 20:32:14.583964Z] Finished read-buildbot-config step (success) 12:32:14 INFO - [mozharness: 2017-01-07 20:32:14.584055Z] Running download-and-extract step. 12:32:14 INFO - Running pre-action listener: _resource_record_pre_action 12:32:14 INFO - Running main action method: download_and_extract 12:32:14 INFO - mkdir: /builds/slave/test/build/tests 12:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:14 INFO - trying https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 12:32:14 INFO - Downloading https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 12:32:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 12:32:15 INFO - Downloaded 1165 bytes. 12:32:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 12:32:15 INFO - Using the following test package requirements: 12:32:15 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 12:32:15 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 12:32:15 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 12:32:15 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:32:15 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 12:32:15 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 12:32:15 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 12:32:15 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 12:32:15 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 12:32:15 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 12:32:15 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 12:32:15 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 12:32:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 12:32:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 12:32:15 INFO - Fetch https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 12:32:16 INFO - Expected file size: 16234544 12:32:23 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:32:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 12:32:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 12:32:23 INFO - Fetch https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 12:32:23 INFO - Expected file size: 16234544 12:32:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 12:32:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 12:32:30 INFO - Fetch https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 12:32:30 INFO - Expected file size: 14414089 12:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:31 INFO - trying https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg 12:32:31 INFO - Downloading https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 12:32:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 12:32:33 INFO - Downloaded 86145808 bytes. 12:32:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg 12:32:33 INFO - mkdir: /builds/slave/test/properties 12:32:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:32:33 INFO - Writing to file /builds/slave/test/properties/build_url 12:32:33 INFO - Contents: 12:32:33 INFO - build_url:https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg 12:32:34 INFO - Running post-action listener: _resource_record_post_action 12:32:34 INFO - Running post-action listener: set_extra_try_arguments 12:32:34 INFO - [mozharness: 2017-01-07 20:32:34.534508Z] Finished download-and-extract step (success) 12:32:34 INFO - [mozharness: 2017-01-07 20:32:34.534639Z] Running populate-webroot step. 12:32:34 INFO - Running pre-action listener: _resource_record_pre_action 12:32:34 INFO - Running main action method: populate_webroot 12:32:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:32:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:32:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:32:34 INFO - '--tppagecycles', 12:32:34 INFO - '7'], 12:32:34 INFO - 'tests': ['tsvgm']}}, 12:32:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:32:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:32:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:32:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:32:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:32:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:32:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['damp', 'tps']}, 12:32:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'tests': ['damp', 'tps']}, 12:32:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['dromaeo_dom']}, 12:32:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:32:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['basic_compositor_video']}, 12:32:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:32:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['a11yr', 12:32:34 INFO - 'ts_paint', 12:32:34 INFO - 'tpaint', 12:32:34 INFO - 'sessionrestore', 12:32:34 INFO - 'sessionrestore_no_auto_restore', 12:32:34 INFO - 'tabpaint']}, 12:32:34 INFO - 'other-e10s': {'tests': ['a11yr', 12:32:34 INFO - 'ts_paint', 12:32:34 INFO - 'tpaint', 12:32:34 INFO - 'sessionrestore', 12:32:34 INFO - 'sessionrestore_no_auto_restore', 12:32:34 INFO - 'tabpaint']}, 12:32:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:32:34 INFO - 'ts_paint', 12:32:34 INFO - 'tpaint', 12:32:34 INFO - 'sessionrestore', 12:32:34 INFO - 'sessionrestore_no_auto_restore', 12:32:34 INFO - 'tabpaint']}, 12:32:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:32:34 INFO - 'ts_paint', 12:32:34 INFO - 'tpaint', 12:32:34 INFO - 'sessionrestore', 12:32:34 INFO - 'sessionrestore_no_auto_restore', 12:32:34 INFO - 'tabpaint']}, 12:32:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['a11yr', 12:32:34 INFO - 'ts_paint', 12:32:34 INFO - 'tpaint', 12:32:34 INFO - 'sessionrestore', 12:32:34 INFO - 'sessionrestore_no_auto_restore', 12:32:34 INFO - 'tabpaint']}, 12:32:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['a11yr', 12:32:34 INFO - 'ts_paint', 12:32:34 INFO - 'tpaint', 12:32:34 INFO - 'sessionrestore', 12:32:34 INFO - 'sessionrestore_no_auto_restore', 12:32:34 INFO - 'tabpaint']}, 12:32:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['tsvgx', 12:32:34 INFO - 'tsvgr_opacity', 12:32:34 INFO - 'tart', 12:32:34 INFO - 'tscrollx', 12:32:34 INFO - 'cart']}, 12:32:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:32:34 INFO - 'tsvgr_opacity', 12:32:34 INFO - 'tart', 12:32:34 INFO - 'tscrollx', 12:32:34 INFO - 'cart']}, 12:32:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'talos_options': ['--disable-e10s'], 12:32:34 INFO - 'tests': ['tp5o']}, 12:32:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'tests': ['tp5o']}, 12:32:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'talos_options': ['--disable-e10s', 12:32:34 INFO - '--xperf_path', 12:32:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:32:34 INFO - 'tests': ['tp5n']}, 12:32:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:32:34 INFO - 'talos_options': ['--xperf_path', 12:32:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:32:34 INFO - 'tests': ['tp5n']}}, 12:32:34 INFO - 'talos.zip': {'path': '', 12:32:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:32:34 INFO - Running post-action listener: _resource_record_post_action 12:32:34 INFO - [mozharness: 2017-01-07 20:32:34.553635Z] Finished populate-webroot step (success) 12:32:34 INFO - [mozharness: 2017-01-07 20:32:34.553762Z] Running create-virtualenv step. 12:32:34 INFO - Running pre-action listener: _resource_record_pre_action 12:32:34 INFO - Running main action method: create_virtualenv 12:32:34 INFO - Creating virtualenv /builds/slave/test/build/venv 12:32:34 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 12:32:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:32:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:32:34 INFO - Using real prefix '/tools/python27' 12:32:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:32:35 INFO - Installing distribute.............................................................................................................................................................................................done. 12:32:38 INFO - Installing pip.................done. 12:32:38 INFO - Return code: 0 12:32:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:38 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:38 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 12:32:38 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 12:32:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:38 INFO - 'HOME': '/Users/cltbld', 12:32:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:38 INFO - 'LOGNAME': 'cltbld', 12:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:38 INFO - 'MOZ_NO_REMOTE': '1', 12:32:38 INFO - 'NO_EM_RESTART': '1', 12:32:38 INFO - 'PAGER': '/bin/cat', 12:32:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:38 INFO - 'PWD': '/builds/slave/test', 12:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:38 INFO - 'SHELL': '/bin/bash', 12:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:38 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:38 INFO - 'USER': 'cltbld', 12:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:38 INFO - 'XPC_FLAGS': '0x0', 12:32:38 INFO - 'XPC_SERVICE_NAME': '0', 12:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:38 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 12:32:38 INFO - Cleaning up... 12:32:38 INFO - Return code: 0 12:32:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:38 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:38 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 12:32:38 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 12:32:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:38 INFO - 'HOME': '/Users/cltbld', 12:32:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:38 INFO - 'LOGNAME': 'cltbld', 12:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:38 INFO - 'MOZ_NO_REMOTE': '1', 12:32:38 INFO - 'NO_EM_RESTART': '1', 12:32:38 INFO - 'PAGER': '/bin/cat', 12:32:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:38 INFO - 'PWD': '/builds/slave/test', 12:32:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:38 INFO - 'SHELL': '/bin/bash', 12:32:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:38 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:38 INFO - 'USER': 'cltbld', 12:32:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:38 INFO - 'XPC_FLAGS': '0x0', 12:32:38 INFO - 'XPC_SERVICE_NAME': '0', 12:32:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:38 INFO - Downloading/unpacking psutil>=3.1.1 12:32:38 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 12:32:38 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 12:32:38 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 12:32:38 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 12:32:38 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 12:32:38 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 12:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:32:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:41 INFO - Installing collected packages: psutil 12:32:41 INFO - Running setup.py install for psutil 12:32:41 INFO - building 'psutil._psutil_osx' extension 12:32:41 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 12:32:42 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 12:32:42 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 12:32:42 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 12:32:42 INFO - building 'psutil._psutil_posix' extension 12:32:42 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 12:32:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:32:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:32:42 INFO - ^ 12:32:42 INFO - 1 warning generated. 12:32:42 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 12:32:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:32:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:32:42 INFO - Successfully installed psutil 12:32:42 INFO - Cleaning up... 12:32:42 INFO - Return code: 0 12:32:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:32:42 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')]} 12:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:42 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')]} 12:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:42 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:42 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 12:32:42 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 12:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:42 INFO - 'HOME': '/Users/cltbld', 12:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:42 INFO - 'LOGNAME': 'cltbld', 12:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:42 INFO - 'MOZ_NO_REMOTE': '1', 12:32:42 INFO - 'NO_EM_RESTART': '1', 12:32:42 INFO - 'PAGER': '/bin/cat', 12:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:42 INFO - 'PWD': '/builds/slave/test', 12:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:42 INFO - 'SHELL': '/bin/bash', 12:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:42 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:42 INFO - 'USER': 'cltbld', 12:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:42 INFO - 'XPC_FLAGS': '0x0', 12:32:42 INFO - 'XPC_SERVICE_NAME': '0', 12:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:32:45 INFO - Installing collected packages: mozsystemmonitor 12:32:45 INFO - Running setup.py install for mozsystemmonitor 12:32:45 INFO - Successfully installed mozsystemmonitor 12:32:45 INFO - Cleaning up... 12:32:46 INFO - Return code: 0 12:32:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:32:46 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')]} 12:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:46 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')]} 12:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:46 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:46 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 12:32:46 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 12:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:46 INFO - 'HOME': '/Users/cltbld', 12:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:46 INFO - 'LOGNAME': 'cltbld', 12:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:46 INFO - 'MOZ_NO_REMOTE': '1', 12:32:46 INFO - 'NO_EM_RESTART': '1', 12:32:46 INFO - 'PAGER': '/bin/cat', 12:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:46 INFO - 'PWD': '/builds/slave/test', 12:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:46 INFO - 'SHELL': '/bin/bash', 12:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:46 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:46 INFO - 'USER': 'cltbld', 12:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:46 INFO - 'XPC_FLAGS': '0x0', 12:32:46 INFO - 'XPC_SERVICE_NAME': '0', 12:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:46 INFO - Downloading/unpacking jsonschema==2.5.1 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32: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 12:32:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:32:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:49 INFO - Downloading functools32-3.2.3-2.tar.gz 12:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:32:49 INFO - warning: no files found matching '*.txt' 12:32:49 INFO - no previously-included directories found matching 'build' 12:32:49 INFO - no previously-included directories found matching 'dist' 12:32:49 INFO - no previously-included directories found matching '.git*' 12:32:49 INFO - Installing collected packages: jsonschema, functools32 12:32:49 INFO - Running setup.py install for functools32 12:32:49 INFO - warning: no files found matching '*.txt' 12:32:49 INFO - no previously-included directories found matching 'build' 12:32:49 INFO - no previously-included directories found matching 'dist' 12:32:49 INFO - no previously-included directories found matching '.git*' 12:32:49 INFO - Successfully installed jsonschema functools32 12:32:49 INFO - Cleaning up... 12:32:49 INFO - Return code: 0 12:32:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:32: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')]} 12:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32: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')]} 12:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32: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', '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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:32: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 functools32==3.2.3-2 12:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:49 INFO - 'HOME': '/Users/cltbld', 12:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:49 INFO - 'LOGNAME': 'cltbld', 12:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:49 INFO - 'MOZ_NO_REMOTE': '1', 12:32:49 INFO - 'NO_EM_RESTART': '1', 12:32:49 INFO - 'PAGER': '/bin/cat', 12:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:49 INFO - 'PWD': '/builds/slave/test', 12:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:49 INFO - 'SHELL': '/bin/bash', 12:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:49 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:49 INFO - 'USER': 'cltbld', 12:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:49 INFO - 'XPC_FLAGS': '0x0', 12:32:49 INFO - 'XPC_SERVICE_NAME': '0', 12:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:32:49 INFO - Cleaning up... 12:32:50 INFO - Return code: 0 12:32:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:32:50 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')]} 12:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:50 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')]} 12:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:50 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:50 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 12:32:50 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 12:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:50 INFO - 'HOME': '/Users/cltbld', 12:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:50 INFO - 'LOGNAME': 'cltbld', 12:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:50 INFO - 'MOZ_NO_REMOTE': '1', 12:32:50 INFO - 'NO_EM_RESTART': '1', 12:32:50 INFO - 'PAGER': '/bin/cat', 12:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:50 INFO - 'PWD': '/builds/slave/test', 12:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:50 INFO - 'SHELL': '/bin/bash', 12:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:50 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:50 INFO - 'USER': 'cltbld', 12:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:50 INFO - 'XPC_FLAGS': '0x0', 12:32:50 INFO - 'XPC_SERVICE_NAME': '0', 12:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:50 INFO - Downloading/unpacking blobuploader==1.2.4 12:32:50 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 12:32:50 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 12:32:50 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 12:32:50 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 12:32:50 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 12:32:50 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 12:32:52 INFO - Downloading blobuploader-1.2.4.tar.gz 12:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:32:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:32:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 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 12:32:53 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 12:32:54 INFO - Downloading docopt-0.6.1.tar.gz 12:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:32:54 INFO - Installing collected packages: blobuploader, requests, docopt 12:32:54 INFO - Running setup.py install for blobuploader 12:32:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:32:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:32:54 INFO - Running setup.py install for requests 12:32:54 INFO - Running setup.py install for docopt 12:32:55 INFO - Successfully installed blobuploader requests docopt 12:32:55 INFO - Cleaning up... 12:32:55 INFO - Return code: 0 12:32:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:55 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')]} 12:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:55 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')]} 12:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:55 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:55 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 12:32:55 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 12:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:55 INFO - 'HOME': '/Users/cltbld', 12:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:55 INFO - 'LOGNAME': 'cltbld', 12:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:55 INFO - 'MOZ_NO_REMOTE': '1', 12:32:55 INFO - 'NO_EM_RESTART': '1', 12:32:55 INFO - 'PAGER': '/bin/cat', 12:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:55 INFO - 'PWD': '/builds/slave/test', 12:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:55 INFO - 'SHELL': '/bin/bash', 12:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:55 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:55 INFO - 'USER': 'cltbld', 12:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:55 INFO - 'XPC_FLAGS': '0x0', 12:32:55 INFO - 'XPC_SERVICE_NAME': '0', 12:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-J147mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-akqcYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-GLjA9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-yHu3bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nftH85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:55 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-owOBpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mSrxzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-6I9E9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-h3wmay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-CRbfR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Vqn7PC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-pBPJFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-6XZvWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5dfoNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-iKWnuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:56 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-vf2PS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:57 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-V6NGd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:32:57 INFO - Running setup.py install for manifestparser 12:32:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:32:57 INFO - Running setup.py install for mozcrash 12:32:57 INFO - Running setup.py install for mozdebug 12:32:57 INFO - Running setup.py install for mozdevice 12:32:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:32:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:32:57 INFO - Running setup.py install for mozfile 12:32:57 INFO - Running setup.py install for mozhttpd 12:32:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Running setup.py install for mozinfo 12:32:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Running setup.py install for mozInstall 12:32:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Running setup.py install for mozleak 12:32:58 INFO - Running setup.py install for mozlog 12:32:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Running setup.py install for moznetwork 12:32:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:32:58 INFO - Running setup.py install for mozprocess 12:32:58 INFO - Running setup.py install for mozprofile 12:32:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:32:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:32:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:32:59 INFO - Running setup.py install for mozrunner 12:32:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:32:59 INFO - Running setup.py install for mozscreenshot 12:32:59 INFO - Running setup.py install for moztest 12:32:59 INFO - Running setup.py install for mozversion 12:32:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:32:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:32:59 INFO - Cleaning up... 12:32:59 INFO - Return code: 0 12:32:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:59 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:32:59 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 12:32:59 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 12:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:59 INFO - 'HOME': '/Users/cltbld', 12:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:59 INFO - 'LOGNAME': 'cltbld', 12:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:59 INFO - 'MOZ_NO_REMOTE': '1', 12:32:59 INFO - 'NO_EM_RESTART': '1', 12:32:59 INFO - 'PAGER': '/bin/cat', 12:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:59 INFO - 'PWD': '/builds/slave/test', 12:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:59 INFO - 'SHELL': '/bin/bash', 12:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:32:59 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:59 INFO - 'USER': 'cltbld', 12:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:59 INFO - 'XPC_FLAGS': '0x0', 12:32:59 INFO - 'XPC_SERVICE_NAME': '0', 12:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-P9qWUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-8xKFBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-z6PGL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-L0XyEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7YWj8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-AxMrH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-a59L8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7rzDaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-tDfuEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:33:00 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)) 12:33:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:33:00 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Ls1SCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:33:01 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-GVlB7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:33:01 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-WMdXda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-R7yDYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:33:01 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hu3rDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mjPiYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:33:01 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-UEzhvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 12:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:33:01 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Q3luV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:33:01 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)) 12:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:33:01 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)) 12:33:01 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:33:01 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:33:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:33:01 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 12:33:01 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 12:33:01 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 12:33:01 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 12:33:01 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 12:33:01 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 12:33:04 INFO - Downloading blessings-1.6.tar.gz 12:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:33:04 INFO - Installing collected packages: blessings 12:33:04 INFO - Running setup.py install for blessings 12:33:04 INFO - Successfully installed blessings 12:33:04 INFO - Cleaning up... 12:33:04 INFO - Return code: 0 12:33:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:33:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:33:05 INFO - Reading from file tmpfile_stdout 12:33:05 INFO - Current package versions: 12:33:05 INFO - blessings == 1.6 12:33:05 INFO - blobuploader == 1.2.4 12:33:05 INFO - docopt == 0.6.1 12:33:05 INFO - functools32 == 3.2.3-2 12:33:05 INFO - jsonschema == 2.5.1 12:33:05 INFO - manifestparser == 1.1 12:33:05 INFO - mozInstall == 1.12 12:33:05 INFO - mozcrash == 0.17 12:33:05 INFO - mozdebug == 0.1 12:33:05 INFO - mozdevice == 0.48 12:33:05 INFO - mozfile == 1.2 12:33:05 INFO - mozhttpd == 0.7 12:33:05 INFO - mozinfo == 0.9 12:33:05 INFO - mozleak == 0.1 12:33:05 INFO - mozlog == 3.4 12:33:05 INFO - moznetwork == 0.27 12:33:05 INFO - mozprocess == 0.23 12:33:05 INFO - mozprofile == 0.28 12:33:05 INFO - mozrunner == 6.12 12:33:05 INFO - mozscreenshot == 0.1 12:33:05 INFO - mozsystemmonitor == 0.3 12:33:05 INFO - moztest == 0.7 12:33:05 INFO - mozversion == 1.4 12:33:05 INFO - psutil == 3.1.1 12:33:05 INFO - requests == 1.2.3 12:33:05 INFO - wsgiref == 0.1.2 12:33:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:33:05 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')]} 12:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:33:05 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')]} 12:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:33:05 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:33:05 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 12:33:05 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 12:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:05 INFO - 'HOME': '/Users/cltbld', 12:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:05 INFO - 'LOGNAME': 'cltbld', 12:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:05 INFO - 'MOZ_NO_REMOTE': '1', 12:33:05 INFO - 'NO_EM_RESTART': '1', 12:33:05 INFO - 'PAGER': '/bin/cat', 12:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:05 INFO - 'PWD': '/builds/slave/test', 12:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:05 INFO - 'SHELL': '/bin/bash', 12:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:33:05 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:05 INFO - 'USER': 'cltbld', 12:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:05 INFO - 'XPC_FLAGS': '0x0', 12:33:05 INFO - 'XPC_SERVICE_NAME': '0', 12:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 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)) 12:33:05 INFO - Cleaning up... 12:33:05 INFO - Return code: 0 12:33:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:33:05 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')]} 12:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:33:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:33:05 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')]} 12:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:33:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:33:05 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 0x107878490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077ee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec29cc2ae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, '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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 12:33:05 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 12:33:05 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 12:33:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:33:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:05 INFO - 'HOME': '/Users/cltbld', 12:33:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:05 INFO - 'LOGNAME': 'cltbld', 12:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:05 INFO - 'MOZ_NO_REMOTE': '1', 12:33:05 INFO - 'NO_EM_RESTART': '1', 12:33:05 INFO - 'PAGER': '/bin/cat', 12:33:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:05 INFO - 'PWD': '/builds/slave/test', 12:33:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:05 INFO - 'SHELL': '/bin/bash', 12:33:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:33:05 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:33:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:05 INFO - 'USER': 'cltbld', 12:33:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:05 INFO - 'XPC_FLAGS': '0x0', 12:33:05 INFO - 'XPC_SERVICE_NAME': '0', 12:33:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:33:05 INFO - Cleaning up... 12:33:05 INFO - Return code: 0 12:33:05 INFO - Running post-action listener: _resource_record_post_action 12:33:05 INFO - Running post-action listener: _start_resource_monitoring 12:33:05 INFO - Starting resource monitoring. 12:33:05 INFO - [mozharness: 2017-01-07 20:33:05.621207Z] Finished create-virtualenv step (success) 12:33:05 INFO - [mozharness: 2017-01-07 20:33:05.622174Z] Running install step. 12:33:05 INFO - Running pre-action listener: _resource_record_pre_action 12:33:05 INFO - Running main action method: install 12:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:33:05 INFO - Reading from file tmpfile_stdout 12:33:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:33:05 INFO - Reading from file tmpfile_stdout 12:33:05 INFO - Output received: 12:33:05 INFO - Usage: mozinstall [options] installer 12:33:05 INFO - Options: 12:33:05 INFO - -h, --help show this help message and exit 12:33:05 INFO - -d DEST, --destination=DEST 12:33:05 INFO - Directory to install application into. [default: 12:33:05 INFO - "/builds/slave/test"] 12:33:05 INFO - --app=APP Application being installed. [default: firefox] 12:33:05 INFO - mkdir: /builds/slave/test/build/application 12:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:33:28 INFO - Reading from file tmpfile_stdout 12:33:28 INFO - Output received: 12:33:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:33:28 INFO - Running post-action listener: _resource_record_post_action 12:33:28 INFO - [mozharness: 2017-01-07 20:33:28.125194Z] Finished install step (success) 12:33:28 INFO - [mozharness: 2017-01-07 20:33:28.125339Z] Running run-tests step. 12:33:28 INFO - Running pre-action listener: _resource_record_pre_action 12:33:28 INFO - Running main action method: run_tests 12:33:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:33:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:33:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:33:28 INFO - 2017-01-07 12:33:28.139 screenresolution[1624:7308] starting screenresolution argv=screenresolution get 12:33:28 INFO - 2017-01-07 12:33:28.142 screenresolution[1624:7308] Display 0: 1600x1200x32@60 12:33:28 INFO - 2017-01-07 12:33:28.148 screenresolution[1625:7310] starting screenresolution argv=screenresolution list 12:33:28 INFO - Available Modes on Display 0 12:33:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:33:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:33:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:33:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:33:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:33:28 INFO - Intel Iris: 12:33:28 INFO - Chipset Model: Intel Iris 12:33:28 INFO - Type: GPU 12:33:28 INFO - Bus: Built-In 12:33:28 INFO - VRAM (Dynamic, Max): 1536 MB 12:33:28 INFO - Vendor: Intel (0x8086) 12:33:28 INFO - Device ID: 0x0a2e 12:33:28 INFO - Revision ID: 0x0009 12:33:28 INFO - Displays: 12:33:28 INFO - Display: 12:33:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:33:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:33:28 INFO - Display Serial Number: 1600x1200 60 12:33:28 INFO - Main Display: Yes 12:33:28 INFO - Mirror: Off 12:33:28 INFO - Online: Yes 12:33:28 INFO - Rotation: Supported 12:33:28 INFO - Return code: 0 12:33:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:33:28 INFO - Python 2.7.3 12:33:28 INFO - Return code: 0 12:33:28 INFO - grabbing minidump binary from tooltool 12:33: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')]} 12:33:28 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 0x10768cd30>, '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 0x1074f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107684b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:33:28 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 12:33:28 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 12:33:28 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 12:33:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:33:28 INFO - Return code: 0 12:33:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:33:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:33:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:33:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:33:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:33:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 12:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --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 12:33:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DHghJfhyCH/Render', 12:33:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:33:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:33:28 INFO - 'HOME': '/Users/cltbld', 12:33:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:33:28 INFO - 'LOGNAME': 'cltbld', 12:33:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:33:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:33:28 INFO - 'MOZ_NO_REMOTE': '1', 12:33:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:33:28 INFO - 'NO_EM_RESTART': '1', 12:33:28 INFO - 'PAGER': '/bin/cat', 12:33:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:33:28 INFO - 'PWD': '/builds/slave/test', 12:33:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:33:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:33:28 INFO - 'SHELL': '/bin/bash', 12:33:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ma5IaW6N1h/Listeners', 12:33:28 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 12:33:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:33:28 INFO - 'USER': 'cltbld', 12:33:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:33:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:33:28 INFO - 'XPC_FLAGS': '0x0', 12:33:28 INFO - 'XPC_SERVICE_NAME': '0', 12:33:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:33:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 12:33:28 INFO - mozversion application_buildid: 20170107092756 12:33:28 INFO - mozversion application_changeset: f7191cd859142678729a2abcdf340f97edc3f202 12:33:28 INFO - mozversion application_display_name: Firefox 12:33:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:33:28 INFO - mozversion application_name: Firefox 12:33:28 INFO - mozversion application_remotingname: firefox 12:33:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:33:28 INFO - mozversion application_vendor: Mozilla 12:33:28 INFO - mozversion application_version: 51.0 12:33:28 INFO - mozversion platform_buildid: 20170107092756 12:33:28 INFO - mozversion platform_changeset: f7191cd859142678729a2abcdf340f97edc3f202 12:33:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:33:28 INFO - mozversion platform_version: 51.0 12:33:28 INFO - using testdate: 1483821208 12:33:28 INFO - actual date: 1483821208 12:33:28 INFO - starting webserver on 'localhost:49240' 12:33:28 INFO - SUITE-START | Running 2 tests 12:33:28 INFO - TEST-START | dromaeo_css 12:33:28 INFO - operating with platform_type : mac_ 12:33:28 INFO - Initialising browser for dromaeo_css test... 12:33:28 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpoHB3JW/profile http://localhost:49240/getInfo.html) 12:33:31 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 12:33:31 INFO - PROCESS | 1635 | colorDepth:24 12:33:31 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 12:33:31 INFO - PROCESS | 1635 | __metrics 12:33:31 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:33:31 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:32 INFO - TEST-INFO | 1635: exit 0 12:33:32 INFO - Browser initialized. 12:33:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:33:32 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpoHB3JW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:43 INFO - PROCESS | 1638 | RSS: Main: 168620032 12:33:43 INFO - PROCESS | 1638 | 12:34:35 INFO - PROCESS | 1638 | 1483821275473 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:36:45 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 12:36:47 INFO - PROCESS | 1638 | RSS: Main: 320487424 12:36:47 INFO - PROCESS | 1638 | 12:39:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 12:39:50 INFO - PROCESS | 1638 | RSS: Main: 302112768 12:39:50 INFO - PROCESS | 1638 | 12:42:53 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 12:42:53 INFO - PROCESS | 1638 | RSS: Main: 264757248 12:42:53 INFO - PROCESS | 1638 | 12:45:55 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 12:45:56 INFO - PROCESS | 1638 | RSS: Main: 266723328 12:45:56 INFO - PROCESS | 1638 | 12:48:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 12:49:00 INFO - PROCESS | 1638 | RSS: Main: 262692864 12:49:00 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 12:52:03 INFO - PROCESS | 1638 | RSS: Main: 313159680 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | __start_tp_report 12:52:03 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 12:52:03 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 12:52:03 INFO - PROCESS | 1638 | |i|pagename|runs| 12:52:03 INFO - PROCESS | 1638 | |0;dojo.html;3278.672132786721;3441.9846145387114;3457.273972465782;3467.7399195060375;3517.6834084932366;5143.765496280894;5332.493413125747;5702.857428564283;5742.971285143573;5759.45285197906;4697.318888761128;5445.529706979113;5685.545156387489;5795.014847475929;5807.912881306784;5229.843104706855;5255.001549705544;5270.156774916014;5296.735163241842;5314.4951229633125;5255.789768409259;5260.368755749316;5269.6311258211945;5357.464253574651;5416.972915135439;4938.234573641092;5279.1817268323475;5321.388040375348;5331.946680533203;5341.4925582069645;5281.551068159192;5317.335333083364;5317.361916569997;5369.275147855068;5375.489328513785;5225.2162175673575;5277.47225277473;5314.654547454403;5319.175527793198;5320.441353657888;5611.270534830487;5658.660480371191;5659.60382773206;5675.318961724578;5775.220345253379;6239.937600624026;6297.244330680302;6345.936540634627;6347.0479428086155;6364.681765911732;6303.810885673392;6316.210473690789;6318.557700960934;6336.398042185985;6351.174347334817;5726.885462290814;5766.682832444172;5775.595708300462;5917.792877249319;5918.822435326947;6216.098665693539;6256.74973001084;6280.246370435577;6318.304986451487;6323.430891219812;6222.160008398899;6232.065190221503;6245.219347581552;6245.406686364788;6251.812445626639;6163.414475624809;6188.535859810442;6249.812505624838;6321.1782468278825;6335.904961425582;5603.683134463397;6108.633481991094;6195.628262304276;6268.905966410507;6289.7484100636375;6344.524160687967;6370.713317900706;6371.171747672773;6372.076048972873;6380.808575742735;4876.975615121902;5024.949750502521;5031.144705400091;5040.521150490697;5086.0845047891735;4437.733735975852;4438.134563760101;4493.393391892118;4505.932411013838;4508.842190523316;5611.971940140274;5948.345681974979;6035.366286539938;6061.363556826558;6081.695915204222;5450.727463626803;6323.27282362525;6329.303776584572;6342.112104305401;6346.460550853137;5714.05718056516;5803.216565763652;5842.620229685057;5924.229850119457;5952.9107063394085;5452.972735136299;5506.394296627368;5514.751836167382;5544.944550554443;5557.333120025542;6006.189164462741;6013;6402.167718196698;6403.135576697179;6429.45349645276;6121.483458747687;6235.345288744798;6305.274893387319;6324.304326523987;6349.904751428639;6247.250329960434;6253.96873015632;6269.279032911091;6322.747090116528;6364.427201551883;6269.655168965751;6282;6283.151774510383;6284;6305.211848518935;6076.756929722762;6318.147050148171;6321.0834429008455;6335.873282534416;6374.107624932606;6023.427774361428;6079.817605471843;6137.171481850071;6142.498775697988;6168.228971378578;6161.137440758387;6230.096635987666;6230.470410015018;6238.157848690369;6244.250689917058;6044.90932636002;6087;6089.786857459968;6095.299040610209;6098.29869564988;6031.4270144336215;6070.028795392716;6084.847878803065;6103.450689437972;6141.508679305734;6080.422359875804;6095.420935011167;6106.3283038866575;6108.267007959073;6140.785072522441 12:52:03 INFO - PROCESS | 1638 | |1;ext.html;505.9392872855257;527.2039220776626;543.3941155611493;551.1402212548425;565.1522715926109;28432.431351372987;41312;42473;42493;42580;43390.783046084725;43768;43905;44018.779906100506;44191.55808441923;9952.452615106184;10130.797384052314;10141.847872281922;10144.847827282596;10149.731283589552;9314.556243782225;9509.33434659574;12208.32854193019;12483.438245278985;12504.3747812609;12244.755104897897;12345.13584049117;12362.567310144146;12366.134370594062;12368.010559155291;12341.814872776915;12418.441170147365;12433.005359571212;12433.93783031079;12440.626781196488;12140.696482588006;12183.269003859798;12189.963853072411;12200.450979705935;12209.816852747215;41169.764907052835;41410.79294603538;41481.792591037156;41495.7925210375;41501;3620.9094772630633;3681.061329360996;3935.291647503448;4131.8967025824295;4214.494260688706;29898;29952;31944;32517.51223731646;32632.347353053043;3396.626371099177;3427.3316703242876;3437.9312413751586;3530.770499917523;3552.0587044432987;497405;502158;504022.99999999633;505176;505709;33867.83066084703;57950.7102464485;58315.708421457624;58354.4164558349;58355.70822145947;34516;35809.28381432409;35921.64078359183;36032.27935441329;36040;15451.227438628024;15570.766438503431;15577.221138943007;15592.376304947675;15598.610034749223;12094;12318.692032699075;13138.948884089441;13229.338533073307;13261.270630115436;7827.1390147083775;7988.400869934778;7998.440109192417;8046.637901294456;8118.10700822909;3315;3357.6306606273292;3360.260742636616;3361.3949489092197;3386.6782655647676;882.8611091691646;932.9860052099224;950.6340059077305;954.6229239450518;966.7148191283588;25524;29693.40613187724;30411.391772164876;30781.076567703003;30964.771123709645;1041.770810421706;1091.9890801092047;1104.9834252486219;1152.0380482297376;1172.4313707851686;12641;15887.602809929844;15956.521304360887;15971.440999565191;16273.104979226257;627219.8639006775;634003;636143;636718;638160.8091959511;42949.35575966363;43240.35139472911;43250;43258.78370608127;43357;1242.5961562492225;1297.4031945305264;1324.1922427319328;1409.9295035248401;1432.684809341943;30903.536446953764;30988;31913.680863192;31919.840400797846;32404.513932290563;48452.51547484621;48995.020099597095;49067.7546612279;49186;49203.26195107148;513448;515597;518145;518976;615323;23773.762262377153;26228.60657090107;26517.60223596609;26627.80174892173;27493.86253068722;39292.60707392889;47372.2894156581;47760;47867.28199077086;47932.760336198095;38315;38386.80806595949;39320.2135957285;39538.209235815695;40435.79782101188;36017;37313;37477.81261093677;37730.056748580406;38589.6141038586;38654.6134538651;39132.608673912895;39283.214335713696;39339.60660393359;39376.606233937295;32655;34557;35111;35188.82405587956;35217.647823522464;25945;26089.347766305993;26094.60858087168;26338.736612633627;27740.861295693394 12:52:03 INFO - PROCESS | 1638 | |2;jquery.html;329.7872871997561;390.7069697726705;395.7269484056002;407.3929844531645;417.1094712788196;19674.606507869834;21917.671234931488;22022.88988555059;22069.227577034806;22369.328920132422;20350.185992560317;21191;21266.46833829155;22083.227087051957;22275.44311392216;5711.685857277858;6135.233095863017;6208.627482351051;6214.223222097238;6221.471174950135;4431.357453169288;4525.728456292616;4525.773711314438;4588.26587745959;6105.389461053904;4663.48701642819;6865.862682746342;6871.622060786654;6931.202911665173;6957.269486703899;5957.374475680034;6276.089966954812;6860.31396860315;6910;6930.584164950119;6163.674809915845;6181.938180618181;6182.783602573933;6187.195664563623;6190.473809726179;25872;26147.477050458885;26166.95332186729;26411.81146848377;26757.464850703072;2259.8644081355005;2583.9147558025675;2651.20463860841;2745.9862700686367;2791.734785195403;9886.901130988743;10071.647492337806;10178.03308685681;10799.460026998697;10804.459777011118;1887.9811201887942;1962.4014675523958;1985.7021446782953;2085.551606404615;2108.515041540454;121015;123527;123556;125670;126558.36720816429;11592.913275610303;11811.409429528489;11847.407629618485;11870.703232419259;11891.821622675667;15167.24163791806;15210.239488025776;15224.391024359124;15240.390384384722;15277.236138193268;8933;9044.547772611475;9088.954555227181;9096.317776166814;9195.310351723647;9019.233365163904;9029.051949545334;9033.15455631474;9186.586603602855;9200.171984521425;4632.374629425052;4636.675432719677;4641.280601506772;4646.140464014168;4724.126036683225;1727.7322015087682;1746.8951862888268;1812.74822067557;1825.1847739338484;1916.176044300964;901.6708901250996;926.1201858234704;927.9907200928039;968.4431451915177;1030.7320096774893;21259.830709311136;22356.664650030263;22429.88785056097;22513.212037578607;22727.545449090925;923.6720964057797;995.6216637677636;1001.2840818814583;1005.9144972677404;1025.825609646362;11332.376719280353;11738.530458781725;11765.588204412805;11837.822432663517;11853.407329633485;453851;531303;580089;617126;617388;108041;138630;143778;144838;145745;964.2912459342377;990.5344488090586;1010.7574182196367;1012.5645972232011;1014.5333146752577;14691.55925322242;19561.706574401112;20191.899040504704;20755.584888302452;21804;55545.444545554026;55998.44001559932;56321.87356252934;58614.4138558609;58872.705636471546;80436.59781701288;81181;82354.76467852868;83436.58281708552;83779;7301.853962920605;7621.1616722161625;8256.13310602378;8316.5425901576;8458.492490450593;12459.377031148588;12931.5473958411;13053.34733263352;13164.341782910624;13454.250947376779;11117.610883619034;11189.664310070712;11345.716357091138;11537.365444900612;11767.528339231038;10052.447115408722;10726.87367826367;11441.970222679998;11587.362695051854;11793.941030294794;11471.827922581;11558.904998378162;11841.763164736829;11988.160828741904;12069.336186509827;7230.060092187982;7433.293837085468;7560.243975602396;7807.453478256468;7982;10683.786324273315;11078.947499987487;11293.322400655987;11478.139139564399;11585.957263846294 12:52:03 INFO - PROCESS | 1638 | |3;mootools.html;1513.9772903406447;1541.7456119740243;1677.6812405642927;1823.6626224148524;1854.0822292964983;8768.78078048049;9340.57967391468;9354.345195836295;9354.719358419241;9363.48500832454;8136.267735903767;8374.58127093646;8918.509481978488;9095.363324567266;9185.90814091859;2426.0780903256737;2451.901923923041;2456.45957889265;2457.4102215468247;2458.299384675368;2423.8909249083836;2436.878156092197;2457.5699252630807;2458.3485376375274;2465.4452748131616;2438.3233058113387;2451.8161137914726;2464.852108873464;2465.5315490056923;2467.2721547143638;2018.3238615063824;2417.3714834143248;2426.4297889995837;2434.7078350597953;2451.203358908362;2418.8427752196226;2430.3195105370523;2434.8295619306655;2435.926922192337;2439.3901524618846;9256.07439256069;9297.953510232473;9399.953000235024;9414.340996130273;9428.434293942317;5490.670559766427;5644.6613203207935;5648.096304591287;5650.858728531778;5651.971740141273;3454.65453454655;3565.8038807865555;3612.819359032038;3643.125649844045;3677.9264414711556;949.8100379923974;1081.6106201767357;1082.2802836113974;1093.2456604942565;1107.5459061784711;12348.5060597576;12355.196912200678;12427.93786031073;12440.253584784941;12450.688732781662;4609.055143695535;4641.883952901205;4830.903381932341;4837.879053023703;4841.8547443576745;9633.662821801343;9648.75878103053;9650.420974741539;9652;9656.420614763138;5505.9724701376235;5508.64193827408;5512.5589952803675;5516.062269414209;5524.116141417434;4234.8142520094425;4446.599806017474;4613.400257966443;4742.810287588527;5150.536451719363;3891.2217556488818;3922.2743792398496;3985.621365970286;4013.678905687538;4019.055521952339;1192.3263356203718;1199.2145144930084;1203.8615559210803;1213.969650758738;1214.7449035702425;924.1728652855791;925.6713866577403;932.3333816321227;939.4738946190155;949.2500924269888;5814.563907706939;5839.941600583939;6040.607360521552;6195.172721415437;6215.409536094064;458.81876658720284;463.62214794942014;471.3660127129006;474.4377912174016;479.7001873828857;3945.763254204757;3969.622885825842;4018.7789671568344;4030.818613162356;4077.3883917412627;11088.5564577416;11561;11565.074794016458;11585.768284634429;11599.826002609967;11499.310041397543;11504.194706370641;11511.884881151249;11528.365939873387;11542.826857597143;8661.393702440768;8675.479471231747;8700;8705.47767133974;8720.258778003814;5919.378465261086;5965.940340596539;6006.339302676614;6127.295361033538;6288.182536270256;10191.388516689023;10391;10535;10628.574857005633;10710.678679639623;10626.946865265625;10854.945725271638;10896.964788345094;10913.017828395483;11042.723931901766;6464.870702586016;6587.538872278895;7899.328557072713;7935.1271360149185;7937.365010799238;8615.827683446421;8677.219050285505;8678.045415004472;8689.261412779984;8697.999730031128;7763.534187948741;7823.882641760264;7833.334166595903;7887.290143887078;7889.605519724106;7631.38948884099;7668;7717.961410192913;7779.805504862424;7804.2585954334245;6981.825454363681;7040.366367026787;7136.785896423115;7145.928540714526;7158.713651453883;4962.156433406401;5018;5066.442691304027;5145.125328694206;5279;4376.781160942004;5048.343715316985;5073.670211436245;5178.145605974972;5186.144286192887 12:52:03 INFO - PROCESS | 1638 | |4;prototype.html;295.7205440858388;296.8486072103227;306.2496882637538;308.74374269356423;311.74125475855044;4448.532904045077;4706.834451510413;5119.513646203604;5354.785808567657;5498.642588231762;4536.863894083174;4541.841035563748;4790.993891282826;5347.652402593829;5461.42655021223;3353.765236433451;3690.748643327568;4210.252745110775;4217.683673724468;4228.386883901832;4211.747295162285;4213.873583792491;4222.873313800591;4274.957250427503;4275.87172384829;3798.518577754664;3986.1828325193264;4136.544980052192;4142.730722503043;4184.958150418503;4119.588041195886;4145.875623731293;4164.146349998243;4178.477690288714;4195.2029114468305;3297.851596678155;3383.0020144057316;3607.6211997740384;3619.791926924574;3636.181859081712;5046.823361182378;5323.946760532383;5350.3312085989255;5383.353997520284;5393.973030134864;4750.738709370983;4793.424789025305;4817.434333841516;4825.372701548811;4834.20235661119;5215.713135777531;5719.59962802604;5732.856678583027;5743.253377060997;5793.391693872125;3483.965160348415;3938.4289278054807;4010.0776821331256;4033.7176397652174;4067.8983025424304;4773.997460533292;5337.492938170868;5535.667859928417;6013.4888534474185;6020.127081573191;3573.141966177573;4073.287174744467;4082.5504697181455;4094.9492627992336;4115.855945041909;3483.912902177415;3696.482492451059;4149.128682976609;4164.979175104105;4181.665466762652;4440.934175797785;4472.284434490466;4665.650076244296;4800.279958006327;4821.927671084936;5735.684537350402;5768.653880767168;5777.826665200051;5779.190913272061;5797.333306669702;1415.688548519324;1416.6741649420485;1423.6048672300997;1427.0663251294347;1429.1849351323967;839.4627438439404;871.8970502314577;891.5230351761905;898.8629383829461;917.7751450894574;4476.597106260387;4557.794899229542;5423.579782477268;5499.690130145348;5550.555955523549;5829.358770535237;5842.707864606753;5874.206982057368;5881.05903055509;5945.464908158287;5238.633295669343;5350.411454739976;5474.260974768435;5503.954248692736;5549;2797.69190417906;3005.729484346419;3065.049834551296;3077.738392236685;3086.2438702518025;5502.0921547944945;5690.4025077367105;6006.3693312201585;6073.869768557423;6136.601120927215;5234.319538460052;5938.376470470538;5971.28344598651;5987.461128498456;6031.457168854824;5023.066119543929;5166.896662066738;5218.347706536683;5866.589338746332;5908.350081491032;4624.398828152319;4851.878703032452;5071.77177027042;5456.454354564512;5456.91814622773;4731.503192164911;5666.15007748841;5760.0783874579865;5778.537716982715;5785.913211301918;5705.372409035086;5943.58394912352;5956.731947062479;6005.729742161526;6035.094735789667;3898.3372826618975;4001.7198796083994;4010.47863777707;4032.91934161321;4035.91928161441;6004.699765011645;6038.426349496791;6087.847803804941;6102;6182.752689892354;4605.976970115128;5167.92248116271;5437.836864893901;5795.449432303924;5981.3719559445635;4575.016371480147;4979.526944940225;5714.942850571441;5753.5834949322225;5820.970895145497;5312.601554883322;5488.286522752017;5515.200295957144;5625.381208067028;5629.493345598916;4798.8884890648005;5549;5640.830775076754;5791.249156650557;5837.227317024549;5729.369769325288;5734.799282025109;5737.36485101744;5752.942470575241;5761.769529218785 12:52:03 INFO - PROCESS | 1638 | |5;yui.html;703.4899697719154;768.162702654107;817.55034730898;829.4359835311988;863.214474827906;15847.366105355786;16103.677926441464;16898.66202675946;16939.23773430194;16957.915210423962;15292.770608440855;15335.69328613427;15499.767503487428;15506.302216400261;15510.14694191819;704.9400800931927;848.5375470368633;864.4597126795753;864.9783755406133;866.5017614871442;728.4936968806655;872.7774417523523;889.7330800759779;898.6360523987795;901.1709227510673;946.1768261612373;946.2524605561632;946.8911075226368;948.7485816258697;948.8766460360143;940.4216406909701;940.8823897012874;941.4398432932463;942.7218970403713;945.5981207986613;700.0514303119302;700.5411455496658;700.751233312172;701.3267263427065;704.718112754897;15446.691066178613;15466.845331546652;15469.613259668486;15470.767938480933;15472.303746331441;13345.532906348328;14751;14846.1834599097;14894.329755161043;15072.472463463835;13153.408096635674;13234.27211503367;13356.532521361705;13474.663133421645;13561.86438135616;4645.907081858378;4708.576228139484;4726.480087190406;4742.525747425264;4874.731889746063;122638.38680806628;123928;127177;131133.34433327868;132005.99999999904;11045.558177672803;11060.281081729661;11238.7190320241;11293.887061129284;11555.306681599131;21305.691654823717;21315.573688526452;21653.78346216549;21673.349799505726;21686.34940951774;7769.53382797034;7968.6414111363965;8007.279344858991;8055.516668999761;8147.226013528705;4454.665900057508;4515.661325400542;4545.568171023748;4581.610563102107;4621.468531118964;3607.9639203608153;3758.8496460141837;3765.679917207013;3767.9434808477895;3767.9811600942367;669.2337273821447;692.875282449164;736.7053178728552;737.0712901743841;741.6884908338511;545.7271364317841;658.3942772649174;683.3712984054681;698.3400686351349;712.5332906945855;9493.715188544355;9587.233021358275;9687.951560242154;9712.271579631415;9828.606855725833;662.5566367080107;689.4518857508293;692.6259819697408;697.8778713725179;700.3392423422134;4163.458750362433;4542.704724192917;4547.806188439001;4661.254199328091;4710.62315014798;84574.57712711397;97737.51131244298;124133;135635;171140;133975;140300;140997;144663;147451;100310.49534256992;117533.824661754;119376.20935685972;119841;124923;7710.961445192738;8872.068432814462;8909.465432074096;8923;8973;57390.7130464345;59537;60686.39313606807;64425.67787161034;65919;67914.64170716458;81582;83248;88341.67487487821;94597.05402945883;13553.390097445836;13860.168389896638;13977.371018303997;13987.020908536304;14137.858621413654;14360.640983975483;14421.495247666282;14747.55757327239;14750.492758803994;15114.54656360311;14135.939804514908;14309.356078976263;14622.634434139232;14678.339474723449;15061.548153555412;10966.451677415938;13506.527271545448;13771.586852394445;13872.514461993782;14517.927410362881;13153.145045572008;13530.729385412433;14013.719725605228;14040.227787471787;14097.506587269396;11323.71690707706;11342.432878356214;11414.600488982845;11893.345865977108;12029.218100823418;10096.596136154472;10828.566857325934;11449.42752862337;11508.654740357802;11846.348450835287 12:52:03 INFO - PROCESS | 1638 | __end_tp_report 12:52:03 INFO - PROCESS | 1638 | __start_cc_report 12:52:03 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1440 12:52:03 INFO - PROCESS | 1638 | __end_cc_report 12:52:03 INFO - PROCESS | 1638 | __startTimestamp1483822323342__endTimestamp 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | ------- Summary: start ------- 12:52:03 INFO - PROCESS | 1638 | Number of tests: 6 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5794.63 Median:6076.76 stddev:642.27 (10.6%) stddev-sans-first:613.17 12:52:03 INFO - PROCESS | 1638 | Values: 3278.7 3442.0 3457.3 3467.7 3517.7 5143.8 5332.5 5702.9 5743.0 5759.5 4697.3 5445.5 5685.5 5795.0 5807.9 5229.8 5255.0 5270.2 5296.7 5314.5 5255.8 5260.4 5269.6 5357.5 5417.0 4938.2 5279.2 5321.4 5331.9 5341.5 5281.6 5317.3 5317.4 5369.3 5375.5 5225.2 5277.5 5314.7 5319.2 5320.4 5611.3 5658.7 5659.6 5675.3 5775.2 6239.9 6297.2 6345.9 6347.0 6364.7 6303.8 6316.2 6318.6 6336.4 6351.2 5726.9 5766.7 5775.6 5917.8 5918.8 6216.1 6256.7 6280.2 6318.3 6323.4 6222.2 6232.1 6245.2 6245.4 6251.8 6163.4 6188.5 6249.8 6321.2 6335.9 5603.7 6108.6 6195.6 6268.9 6289.7 6344.5 6370.7 6371.2 6372.1 6380.8 4877.0 5024.9 5031.1 5040.5 5086.1 4437.7 4438.1 4493.4 4505.9 4508.8 5612.0 5948.3 6035.4 6061.4 6081.7 5450.7 6323.3 6329.3 6342.1 6346.5 5714.1 5803.2 5842.6 5924.2 5952.9 5453.0 5506.4 5514.8 5544.9 5557.3 6006.2 6013.0 6402.2 6403.1 6429.5 6121.5 6235.3 6305.3 6324.3 6349.9 6247.3 6254.0 6269.3 6322.7 6364.4 6269.7 6282.0 6283.2 6284.0 6305.2 6076.8 6318.1 6321.1 6335.9 6374.1 6023.4 6079.8 6137.2 6142.5 6168.2 6161.1 6230.1 6230.5 6238.2 6244.3 6044.9 6087.0 6089.8 6095.3 6098.3 6031.4 6070.0 6084.8 6103.5 6141.5 6080.4 6095.4 6106.3 6108.3 6140.8 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:67934.63 Median:35865.46 stddev:150016.10 (418.3%) stddev-sans-first:150351.51 12:52:03 INFO - PROCESS | 1638 | Values: 505.9 527.2 543.4 551.1 565.2 28432.4 41312.0 42473.0 42493.0 42580.0 43390.8 43768.0 43905.0 44018.8 44191.6 9952.5 10130.8 10141.8 10144.8 10149.7 9314.6 9509.3 12208.3 12483.4 12504.4 12244.8 12345.1 12362.6 12366.1 12368.0 12341.8 12418.4 12433.0 12433.9 12440.6 12140.7 12183.3 12190.0 12200.5 12209.8 41169.8 41410.8 41481.8 41495.8 41501.0 3620.9 3681.1 3935.3 4131.9 4214.5 29898.0 29952.0 31944.0 32517.5 32632.3 3396.6 3427.3 3437.9 3530.8 3552.1 497405.0 502158.0 504023.0 505176.0 505709.0 33867.8 57950.7 58315.7 58354.4 58355.7 34516.0 35809.3 35921.6 36032.3 36040.0 15451.2 15570.8 15577.2 15592.4 15598.6 12094.0 12318.7 13138.9 13229.3 13261.3 7827.1 7988.4 7998.4 8046.6 8118.1 3315.0 3357.6 3360.3 3361.4 3386.7 882.9 933.0 950.6 954.6 966.7 25524.0 29693.4 30411.4 30781.1 30964.8 1041.8 1092.0 1105.0 1152.0 1172.4 12641.0 15887.6 15956.5 15971.4 16273.1 627219.9 634003.0 636143.0 636718.0 638160.8 42949.4 43240.4 43250.0 43258.8 43357.0 1242.6 1297.4 1324.2 1409.9 1432.7 30903.5 30988.0 31913.7 31919.8 32404.5 48452.5 48995.0 49067.8 49186.0 49203.3 513448.0 515597.0 518145.0 518976.0 615323.0 23773.8 26228.6 26517.6 26627.8 27493.9 39292.6 47372.3 47760.0 47867.3 47932.8 38315.0 38386.8 39320.2 39538.2 40435.8 36017.0 37313.0 37477.8 37730.1 38589.6 38654.6 39132.6 39283.2 39339.6 39376.6 32655.0 34557.0 35111.0 35188.8 35217.6 25945.0 26089.3 26094.6 26338.7 27740.9 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34901.13 Median:12493.70 stddev:94837.68 (759.1%) stddev-sans-first:95068.20 12:52:03 INFO - PROCESS | 1638 | Values: 329.8 390.7 395.7 407.4 417.1 19674.6 21917.7 22022.9 22069.2 22369.3 20350.2 21191.0 21266.5 22083.2 22275.4 5711.7 6135.2 6208.6 6214.2 6221.5 4431.4 4525.7 4525.8 4588.3 6105.4 4663.5 6865.9 6871.6 6931.2 6957.3 5957.4 6276.1 6860.3 6910.0 6930.6 6163.7 6181.9 6182.8 6187.2 6190.5 25872.0 26147.5 26167.0 26411.8 26757.5 2259.9 2583.9 2651.2 2746.0 2791.7 9886.9 10071.6 10178.0 10799.5 10804.5 1888.0 1962.4 1985.7 2085.6 2108.5 121015.0 123527.0 123556.0 125670.0 126558.4 11592.9 11811.4 11847.4 11870.7 11891.8 15167.2 15210.2 15224.4 15240.4 15277.2 8933.0 9044.5 9089.0 9096.3 9195.3 9019.2 9029.1 9033.2 9186.6 9200.2 4632.4 4636.7 4641.3 4646.1 4724.1 1727.7 1746.9 1812.7 1825.2 1916.2 901.7 926.1 928.0 968.4 1030.7 21259.8 22356.7 22429.9 22513.2 22727.5 923.7 995.6 1001.3 1005.9 1025.8 11332.4 11738.5 11765.6 11837.8 11853.4 453851.0 531303.0 580089.0 617126.0 617388.0 108041.0 138630.0 143778.0 144838.0 145745.0 964.3 990.5 1010.8 1012.6 1014.5 14691.6 19561.7 20191.9 20755.6 21804.0 55545.4 55998.4 56321.9 58614.4 58872.7 80436.6 81181.0 82354.8 83436.6 83779.0 7301.9 7621.2 8256.1 8316.5 8458.5 12459.4 12931.5 13053.3 13164.3 13454.3 11117.6 11189.7 11345.7 11537.4 11767.5 10052.4 10726.9 11442.0 11587.4 11793.9 11471.8 11558.9 11841.8 11988.2 12069.3 7230.1 7433.3 7560.2 7807.5 7982.0 10683.8 11078.9 11293.3 11478.1 11586.0 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5912.63 Median:2655.30 stddev:3418.00 (128.7%) stddev-sans-first:3411.61 12:52:03 INFO - PROCESS | 1638 | Values: 1514.0 1541.7 1677.7 1823.7 1854.1 8768.8 9340.6 9354.3 9354.7 9363.5 8136.3 8374.6 8918.5 9095.4 9185.9 2426.1 2451.9 2456.5 2457.4 2458.3 2423.9 2436.9 2457.6 2458.3 2465.4 2438.3 2451.8 2464.9 2465.5 2467.3 2018.3 2417.4 2426.4 2434.7 2451.2 2418.8 2430.3 2434.8 2435.9 2439.4 9256.1 9298.0 9400.0 9414.3 9428.4 5490.7 5644.7 5648.1 5650.9 5652.0 3454.7 3565.8 3612.8 3643.1 3677.9 949.8 1081.6 1082.3 1093.2 1107.5 12348.5 12355.2 12427.9 12440.3 12450.7 4609.1 4641.9 4830.9 4837.9 4841.9 9633.7 9648.8 9650.4 9652.0 9656.4 5506.0 5508.6 5512.6 5516.1 5524.1 4234.8 4446.6 4613.4 4742.8 5150.5 3891.2 3922.3 3985.6 4013.7 4019.1 1192.3 1199.2 1203.9 1214.0 1214.7 924.2 925.7 932.3 939.5 949.3 5814.6 5839.9 6040.6 6195.2 6215.4 458.8 463.6 471.4 474.4 479.7 3945.8 3969.6 4018.8 4030.8 4077.4 11088.6 11561.0 11565.1 11585.8 11599.8 11499.3 11504.2 11511.9 11528.4 11542.8 8661.4 8675.5 8700.0 8705.5 8720.3 5919.4 5965.9 6006.3 6127.3 6288.2 10191.4 10391.0 10535.0 10628.6 10710.7 10626.9 10854.9 10897.0 10913.0 11042.7 6464.9 6587.5 7899.3 7935.1 7937.4 8615.8 8677.2 8678.0 8689.3 8698.0 7763.5 7823.9 7833.3 7887.3 7889.6 7631.4 7668.0 7718.0 7779.8 7804.3 6981.8 7040.4 7136.8 7145.9 7158.7 4962.2 5018.0 5066.4 5145.1 5279.0 4376.8 5048.3 5073.7 5178.1 5186.1 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4631.76 Median:5191.82 stddev:1409.51 (27.1%) stddev-sans-first:1375.37 12:52:03 INFO - PROCESS | 1638 | Values: 295.7 296.8 306.2 308.7 311.7 4448.5 4706.8 5119.5 5354.8 5498.6 4536.9 4541.8 4791.0 5347.7 5461.4 3353.8 3690.7 4210.3 4217.7 4228.4 4211.7 4213.9 4222.9 4275.0 4275.9 3798.5 3986.2 4136.5 4142.7 4185.0 4119.6 4145.9 4164.1 4178.5 4195.2 3297.9 3383.0 3607.6 3619.8 3636.2 5046.8 5323.9 5350.3 5383.4 5394.0 4750.7 4793.4 4817.4 4825.4 4834.2 5215.7 5719.6 5732.9 5743.3 5793.4 3484.0 3938.4 4010.1 4033.7 4067.9 4774.0 5337.5 5535.7 6013.5 6020.1 3573.1 4073.3 4082.6 4094.9 4115.9 3483.9 3696.5 4149.1 4165.0 4181.7 4440.9 4472.3 4665.7 4800.3 4821.9 5735.7 5768.7 5777.8 5779.2 5797.3 1415.7 1416.7 1423.6 1427.1 1429.2 839.5 871.9 891.5 898.9 917.8 4476.6 4557.8 5423.6 5499.7 5550.6 5829.4 5842.7 5874.2 5881.1 5945.5 5238.6 5350.4 5474.3 5504.0 5549.0 2797.7 3005.7 3065.0 3077.7 3086.2 5502.1 5690.4 6006.4 6073.9 6136.6 5234.3 5938.4 5971.3 5987.5 6031.5 5023.1 5166.9 5218.3 5866.6 5908.4 4624.4 4851.9 5071.8 5456.5 5456.9 4731.5 5666.2 5760.1 5778.5 5785.9 5705.4 5943.6 5956.7 6005.7 6035.1 3898.3 4001.7 4010.5 4032.9 4035.9 6004.7 6038.4 6087.8 6102.0 6182.8 4606.0 5167.9 5437.8 5795.4 5981.4 4575.0 4979.5 5714.9 5753.6 5821.0 5312.6 5488.3 5515.2 5625.4 5629.5 4798.9 5549.0 5640.8 5791.2 5837.2 5729.4 5734.8 5737.4 5752.9 5761.8 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:25132.38 Median:3766.81 stddev:39974.43 (1061.2%) stddev-sans-first:40044.49 12:52:03 INFO - PROCESS | 1638 | Values: 703.5 768.2 817.6 829.4 863.2 15847.4 16103.7 16898.7 16939.2 16957.9 15292.8 15335.7 15499.8 15506.3 15510.1 704.9 848.5 864.5 865.0 866.5 728.5 872.8 889.7 898.6 901.2 946.2 946.3 946.9 948.7 948.9 940.4 940.9 941.4 942.7 945.6 700.1 700.5 700.8 701.3 704.7 15446.7 15466.8 15469.6 15470.8 15472.3 13345.5 14751.0 14846.2 14894.3 15072.5 13153.4 13234.3 13356.5 13474.7 13561.9 4645.9 4708.6 4726.5 4742.5 4874.7 122638.4 123928.0 127177.0 131133.3 132006.0 11045.6 11060.3 11238.7 11293.9 11555.3 21305.7 21315.6 21653.8 21673.3 21686.3 7769.5 7968.6 8007.3 8055.5 8147.2 4454.7 4515.7 4545.6 4581.6 4621.5 3608.0 3758.8 3765.7 3767.9 3768.0 669.2 692.9 736.7 737.1 741.7 545.7 658.4 683.4 698.3 712.5 9493.7 9587.2 9688.0 9712.3 9828.6 662.6 689.5 692.6 697.9 700.3 4163.5 4542.7 4547.8 4661.3 4710.6 84574.6 97737.5 124133.0 135635.0 171140.0 133975.0 140300.0 140997.0 144663.0 147451.0 100310.5 117533.8 119376.2 119841.0 124923.0 7711.0 8872.1 8909.5 8923.0 8973.0 57390.7 59537.0 60686.4 64425.7 65919.0 67914.6 81582.0 83248.0 88341.7 94597.1 13553.4 13860.2 13977.4 13987.0 14137.9 14360.6 14421.5 14747.6 14750.5 15114.5 14135.9 14309.4 14622.6 14678.3 15061.5 10966.5 13506.5 13771.6 13872.5 14517.9 13153.1 13530.7 14013.7 14040.2 14097.5 11323.7 11342.4 11414.6 11893.3 12029.2 10096.6 10828.6 11449.4 11508.7 11846.3 12:52:03 INFO - PROCESS | 1638 | -------- Summary: end -------- 12:52:03 INFO - PROCESS | 1638 | 12:52:03 INFO - TEST-INFO | 1638: exit 0 12:52:03 INFO - TEST-OK | dromaeo_css | took 1115203ms 12:52:03 INFO - TEST-START | kraken 12:52:03 INFO - operating with platform_type : mac_ 12:52:03 INFO - Initialising browser for kraken test... 12:52:03 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpUkEsAs/profile http://localhost:49240/getInfo.html) 12:52:06 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 12:52:06 INFO - PROCESS | 1648 | colorDepth:24 12:52:06 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 12:52:06 INFO - PROCESS | 1648 | __metrics 12:52:06 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:52:06 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:52:06 INFO - TEST-INFO | 1648: exit 0 12:52:06 INFO - Browser initialized. 12:52:06 INFO - Running cycle 1/1 for kraken test... 12:52:06 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpUkEsAs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:52:18 INFO - PROCESS | 1650 | RSS: Main: 169234432 12:52:18 INFO - PROCESS | 1650 | 12:52:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 12:52:22 INFO - PROCESS | 1650 | RSS: Main: 219389952 12:52:22 INFO - PROCESS | 1650 | 12:52:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 12:52:25 INFO - PROCESS | 1650 | RSS: Main: 232411136 12:52:25 INFO - PROCESS | 1650 | 12:52:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 12:52:29 INFO - PROCESS | 1650 | RSS: Main: 328409088 12:52:29 INFO - PROCESS | 1650 | 12:52:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 12:52:31 INFO - PROCESS | 1650 | RSS: Main: 228376576 12:52:31 INFO - PROCESS | 1650 | 12:52:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:52:34 INFO - PROCESS | 1650 | RSS: Main: 229249024 12:52:34 INFO - PROCESS | 1650 | 12:52:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 12:52:37 INFO - PROCESS | 1650 | RSS: Main: 267218944 12:52:37 INFO - PROCESS | 1650 | 12:52:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 12:52:41 INFO - PROCESS | 1650 | RSS: Main: 267030528 12:52:41 INFO - PROCESS | 1650 | 12:52:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 12:52:44 INFO - PROCESS | 1650 | RSS: Main: 266944512 12:52:44 INFO - PROCESS | 1650 | 12:52:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:52:46 INFO - PROCESS | 1650 | RSS: Main: 240574464 12:52:46 INFO - PROCESS | 1650 | 12:52:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:52:49 INFO - PROCESS | 1650 | RSS: Main: 245370880 12:52:49 INFO - PROCESS | 1650 | 12:52:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:52:51 INFO - PROCESS | 1650 | RSS: Main: 255926272 12:52:51 INFO - PROCESS | 1650 | 12:52:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:52:55 INFO - PROCESS | 1650 | RSS: Main: 265895936 12:52:55 INFO - PROCESS | 1650 | 12:52:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:52:58 INFO - PROCESS | 1650 | RSS: Main: 259825664 12:52:58 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 12:53:00 INFO - PROCESS | 1650 | RSS: Main: 304074752 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | __start_tp_report 12:53:00 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 12:53:00 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 12:53:00 INFO - PROCESS | 1650 | |i|pagename|runs| 12:53:00 INFO - PROCESS | 1650 | |0;ai-astar;115;113;114;112;113;113;113;112;113;112 12:53:00 INFO - PROCESS | 1650 | |1;audio-beat-detection;143;108;105;140;109;109;105;108;137;107 12:53:00 INFO - PROCESS | 1650 | |2;audio-dft;187;180;185;186;184;181;184;184;186;185 12:53:00 INFO - PROCESS | 1650 | |3;audio-fft;80;58;58;88;63;60;57;59;80;57 12:53:00 INFO - PROCESS | 1650 | |4;audio-oscillator;82;69;92;68;68;68;92;90;100;123 12:53:00 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;88;87;89;88;89;87;91;88;89;87 12:53:00 INFO - PROCESS | 1650 | |6;imaging-darkroom;96;97;98;96;97;98;97;97;96;99 12:53:00 INFO - PROCESS | 1650 | |7;imaging-desaturate;85;88;86;86;86;88;94;89;87;92 12:53:00 INFO - PROCESS | 1650 | |8;json-parse-financial;58;52;53;52;56;57;56;56;57;56 12:53:00 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;62;70;78;74;71;67;68;69;68;66 12:53:00 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;85;83;84;81;83;84;80;84;81;86 12:53:00 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;136;134;137;136;139;122;147;127;189;150 12:53:00 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;164;160;161;160;160;158;160;158;163;161 12:53:00 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;64;65;66;66;65;66;67;64;65;64 12:53:00 INFO - PROCESS | 1650 | __end_tp_report 12:53:00 INFO - PROCESS | 1650 | __start_cc_report 12:53:00 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1890 12:53:00 INFO - PROCESS | 1650 | __end_cc_report 12:53:00 INFO - PROCESS | 1650 | __startTimestamp1483822380648__endTimestamp 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | ------- Summary: start ------- 12:53:00 INFO - PROCESS | 1650 | Number of tests: 14 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:0.94 (0.8%) stddev-sans-first:0.67 12:53:00 INFO - PROCESS | 1650 | Values: 115.0 113.0 114.0 112.0 113.0 113.0 113.0 112.0 113.0 112.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:109.00 stddev:15.93 (14.6%) stddev-sans-first:13.86 12:53:00 INFO - PROCESS | 1650 | Values: 143.0 108.0 105.0 140.0 109.0 109.0 105.0 108.0 137.0 107.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:2.20 (1.2%) stddev-sans-first:2.09 12:53:00 INFO - PROCESS | 1650 | Values: 187.0 180.0 185.0 186.0 184.0 181.0 184.0 184.0 186.0 185.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:66.00 Median:61.50 stddev:11.83 (19.2%) stddev-sans-first:11.41 12:53:00 INFO - PROCESS | 1650 | Values: 80.0 58.0 58.0 88.0 63.0 60.0 57.0 59.0 80.0 57.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:75.50 stddev:18.02 (23.9%) stddev-sans-first:19.08 12:53:00 INFO - PROCESS | 1650 | Values: 82.0 69.0 92.0 68.0 68.0 68.0 92.0 90.0 100.0 123.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:1.25 (1.4%) stddev-sans-first:1.32 12:53:00 INFO - PROCESS | 1650 | Values: 88.0 87.0 89.0 88.0 89.0 87.0 91.0 88.0 89.0 87.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:97.00 stddev:0.99 (1.0%) stddev-sans-first:0.97 12:53:00 INFO - PROCESS | 1650 | Values: 96.0 97.0 98.0 96.0 97.0 98.0 97.0 97.0 96.0 99.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:88.10 Median:88.00 stddev:2.88 (3.3%) stddev-sans-first:2.83 12:53:00 INFO - PROCESS | 1650 | Values: 85.0 88.0 86.0 86.0 86.0 88.0 94.0 89.0 87.0 92.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:2.16 (3.9%) stddev-sans-first:2.06 12:53:00 INFO - PROCESS | 1650 | Values: 58.0 52.0 53.0 52.0 56.0 57.0 56.0 56.0 57.0 56.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:69.50 stddev:4.40 (6.3%) stddev-sans-first:3.79 12:53:00 INFO - PROCESS | 1650 | Values: 62.0 70.0 78.0 74.0 71.0 67.0 68.0 69.0 68.0 66.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:84.00 stddev:1.91 (2.3%) stddev-sans-first:1.90 12:53:00 INFO - PROCESS | 1650 | Values: 85.0 83.0 84.0 81.0 83.0 84.0 80.0 84.0 81.0 86.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:138.00 stddev:18.54 (13.4%) stddev-sans-first:19.54 12:53:00 INFO - PROCESS | 1650 | Values: 136.0 134.0 137.0 136.0 139.0 122.0 147.0 127.0 189.0 150.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.50 Median:160.50 stddev:1.90 (1.2%) stddev-sans-first:1.54 12:53:00 INFO - PROCESS | 1650 | Values: 164.0 160.0 161.0 160.0 160.0 158.0 160.0 158.0 163.0 161.0 12:53:00 INFO - PROCESS | 1650 | 12:53:00 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.03 (1.6%) stddev-sans-first:1.00 12:53:00 INFO - PROCESS | 1650 | Values: 64.0 65.0 66.0 66.0 65.0 66.0 67.0 64.0 65.0 64.0 12:53:00 INFO - PROCESS | 1650 | -------- Summary: end -------- 12:53:00 INFO - PROCESS | 1650 | 12:53:01 INFO - TEST-INFO | 1650: exit 0 12:53:01 INFO - TEST-OK | kraken | took 57531ms 12:53:01 INFO - SUITE-END | took 1172s 12:53:01 INFO - Completed test suite (00:19:33) 12:53:01 INFO - javascript benchmark 12:53:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.672132786721, 3441.9846145387114, 3457.273972465782, 3467.7399195060375, 3517.6834084932366, 5143.765496280894, 5332.493413125747, 5702.857428564283, 5742.971285143573, 5759.45285197906, 4697.318888761128, 5445.529706979113, 5685.545156387489, 5795.014847475929, 5807.912881306784, 5229.843104706855, 5255.001549705544, 5270.156774916014, 5296.735163241842, 5314.4951229633125, 5255.789768409259, 5260.368755749316, 5269.6311258211945, 5357.464253574651, 5416.972915135439, 4938.234573641092, 5279.1817268323475, 5321.388040375348, 5331.946680533203, 5341.4925582069645, 5281.551068159192, 5317.335333083364, 5317.361916569997, 5369.275147855068, 5375.489328513785, 5225.2162175673575, 5277.47225277473, 5314.654547454403, 5319.175527793198, 5320.441353657888, 5611.270534830487, 5658.660480371191, 5659.60382773206, 5675.318961724578, 5775.220345253379, 6239.937600624026, 6297.244330680302, 6345.936540634627, 6347.0479428086155, 6364.681765911732, 6303.810885673392, 6316.210473690789, 6318.557700960934, 6336.398042185985, 6351.174347334817, 5726.885462290814, 5766.682832444172, 5775.595708300462, 5917.792877249319, 5918.822435326947, 6216.098665693539, 6256.74973001084, 6280.246370435577, 6318.304986451487, 6323.430891219812, 6222.160008398899, 6232.065190221503, 6245.219347581552, 6245.406686364788, 6251.812445626639, 6163.414475624809, 6188.535859810442, 6249.812505624838, 6321.1782468278825, 6335.904961425582, 5603.683134463397, 6108.633481991094, 6195.628262304276, 6268.905966410507, 6289.7484100636375, 6344.524160687967, 6370.713317900706, 6371.171747672773, 6372.076048972873, 6380.808575742735, 4876.975615121902, 5024.949750502521, 5031.144705400091, 5040.521150490697, 5086.0845047891735, 4437.733735975852, 4438.134563760101, 4493.393391892118, 4505.932411013838, 4508.842190523316, 5611.971940140274, 5948.345681974979, 6035.366286539938, 6061.363556826558, 6081.695915204222, 5450.727463626803, 6323.27282362525, 6329.303776584572, 6342.112104305401, 6346.460550853137, 5714.05718056516, 5803.216565763652, 5842.620229685057, 5924.229850119457, 5952.9107063394085, 5452.972735136299, 5506.394296627368, 5514.751836167382, 5544.944550554443, 5557.333120025542, 6006.189164462741, 6013.0, 6402.167718196698, 6403.135576697179, 6429.45349645276, 6121.483458747687, 6235.345288744798, 6305.274893387319, 6324.304326523987, 6349.904751428639, 6247.250329960434, 6253.96873015632, 6269.279032911091, 6322.747090116528, 6364.427201551883, 6269.655168965751, 6282.0, 6283.151774510383, 6284.0, 6305.211848518935, 6076.756929722762, 6318.147050148171, 6321.0834429008455, 6335.873282534416, 6374.107624932606, 6023.427774361428, 6079.817605471843, 6137.171481850071, 6142.498775697988, 6168.228971378578, 6161.137440758387, 6230.096635987666, 6230.470410015018, 6238.157848690369, 6244.250689917058, 6044.90932636002, 6087.0, 6089.786857459968, 6095.299040610209, 6098.29869564988, 6031.4270144336215, 6070.028795392716, 6084.847878803065, 6103.450689437972, 6141.508679305734, 6080.422359875804, 6095.420935011167, 6106.3283038866575, 6108.267007959073, 6140.785072522441], "value": 5754.402417411969, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.9392872855257, 527.2039220776626, 543.3941155611493, 551.1402212548425, 565.1522715926109, 28432.431351372987, 41312.0, 42473.0, 42493.0, 42580.0, 43390.783046084725, 43768.0, 43905.0, 44018.779906100506, 44191.55808441923, 9952.452615106184, 10130.797384052314, 10141.847872281922, 10144.847827282596, 10149.731283589552, 9314.556243782225, 9509.33434659574, 12208.32854193019, 12483.438245278985, 12504.3747812609, 12244.755104897897, 12345.13584049117, 12362.567310144146, 12366.134370594062, 12368.010559155291, 12341.814872776915, 12418.441170147365, 12433.005359571212, 12433.93783031079, 12440.626781196488, 12140.696482588006, 12183.269003859798, 12189.963853072411, 12200.450979705935, 12209.816852747215, 41169.764907052835, 41410.79294603538, 41481.792591037156, 41495.7925210375, 41501.0, 3620.9094772630633, 3681.061329360996, 3935.291647503448, 4131.8967025824295, 4214.494260688706, 29898.0, 29952.0, 31944.0, 32517.51223731646, 32632.347353053043, 3396.626371099177, 3427.3316703242876, 3437.9312413751586, 3530.770499917523, 3552.0587044432987, 497405.0, 502158.0, 504022.99999999633, 505176.0, 505709.0, 33867.83066084703, 57950.7102464485, 58315.708421457624, 58354.4164558349, 58355.70822145947, 34516.0, 35809.28381432409, 35921.64078359183, 36032.27935441329, 36040.0, 15451.227438628024, 15570.766438503431, 15577.221138943007, 15592.376304947675, 15598.610034749223, 12094.0, 12318.692032699075, 13138.948884089441, 13229.338533073307, 13261.270630115436, 7827.1390147083775, 7988.400869934778, 7998.440109192417, 8046.637901294456, 8118.10700822909, 3315.0, 3357.6306606273292, 3360.260742636616, 3361.3949489092197, 3386.6782655647676, 882.8611091691646, 932.9860052099224, 950.6340059077305, 954.6229239450518, 966.7148191283588, 25524.0, 29693.40613187724, 30411.391772164876, 30781.076567703003, 30964.771123709645, 1041.770810421706, 1091.9890801092047, 1104.9834252486219, 1152.0380482297376, 1172.4313707851686, 12641.0, 15887.602809929844, 15956.521304360887, 15971.440999565191, 16273.104979226257, 627219.8639006775, 634003.0, 636143.0, 636718.0, 638160.8091959511, 42949.35575966363, 43240.35139472911, 43250.0, 43258.78370608127, 43357.0, 1242.5961562492225, 1297.4031945305264, 1324.1922427319328, 1409.9295035248401, 1432.684809341943, 30903.536446953764, 30988.0, 31913.680863192, 31919.840400797846, 32404.513932290563, 48452.51547484621, 48995.020099597095, 49067.7546612279, 49186.0, 49203.26195107148, 513448.0, 515597.0, 518145.0, 518976.0, 615323.0, 23773.762262377153, 26228.60657090107, 26517.60223596609, 26627.80174892173, 27493.86253068722, 39292.60707392889, 47372.2894156581, 47760.0, 47867.28199077086, 47932.760336198095, 38315.0, 38386.80806595949, 39320.2135957285, 39538.209235815695, 40435.79782101188, 36017.0, 37313.0, 37477.81261093677, 37730.056748580406, 38589.6141038586, 38654.6134538651, 39132.608673912895, 39283.214335713696, 39339.60660393359, 39376.606233937295, 32655.0, 34557.0, 35111.0, 35188.82405587956, 35217.647823522464, 25945.0, 26089.347766305993, 26094.60858087168, 26338.736612633627, 27740.861295693394], "value": 19215.532954048947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.7872871997561, 390.7069697726705, 395.7269484056002, 407.3929844531645, 417.1094712788196, 19674.606507869834, 21917.671234931488, 22022.88988555059, 22069.227577034806, 22369.328920132422, 20350.185992560317, 21191.0, 21266.46833829155, 22083.227087051957, 22275.44311392216, 5711.685857277858, 6135.233095863017, 6208.627482351051, 6214.223222097238, 6221.471174950135, 4431.357453169288, 4525.728456292616, 4525.773711314438, 4588.26587745959, 6105.389461053904, 4663.48701642819, 6865.862682746342, 6871.622060786654, 6931.202911665173, 6957.269486703899, 5957.374475680034, 6276.089966954812, 6860.31396860315, 6910.0, 6930.584164950119, 6163.674809915845, 6181.938180618181, 6182.783602573933, 6187.195664563623, 6190.473809726179, 25872.0, 26147.477050458885, 26166.95332186729, 26411.81146848377, 26757.464850703072, 2259.8644081355005, 2583.9147558025675, 2651.20463860841, 2745.9862700686367, 2791.734785195403, 9886.901130988743, 10071.647492337806, 10178.03308685681, 10799.460026998697, 10804.459777011118, 1887.9811201887942, 1962.4014675523958, 1985.7021446782953, 2085.551606404615, 2108.515041540454, 121015.0, 123527.0, 123556.0, 125670.0, 126558.36720816429, 11592.913275610303, 11811.409429528489, 11847.407629618485, 11870.703232419259, 11891.821622675667, 15167.24163791806, 15210.239488025776, 15224.391024359124, 15240.390384384722, 15277.236138193268, 8933.0, 9044.547772611475, 9088.954555227181, 9096.317776166814, 9195.310351723647, 9019.233365163904, 9029.051949545334, 9033.15455631474, 9186.586603602855, 9200.171984521425, 4632.374629425052, 4636.675432719677, 4641.280601506772, 4646.140464014168, 4724.126036683225, 1727.7322015087682, 1746.8951862888268, 1812.74822067557, 1825.1847739338484, 1916.176044300964, 901.6708901250996, 926.1201858234704, 927.9907200928039, 968.4431451915177, 1030.7320096774893, 21259.830709311136, 22356.664650030263, 22429.88785056097, 22513.212037578607, 22727.545449090925, 923.6720964057797, 995.6216637677636, 1001.2840818814583, 1005.9144972677404, 1025.825609646362, 11332.376719280353, 11738.530458781725, 11765.588204412805, 11837.822432663517, 11853.407329633485, 453851.0, 531303.0, 580089.0, 617126.0, 617388.0, 108041.0, 138630.0, 143778.0, 144838.0, 145745.0, 964.2912459342377, 990.5344488090586, 1010.7574182196367, 1012.5645972232011, 1014.5333146752577, 14691.55925322242, 19561.706574401112, 20191.899040504704, 20755.584888302452, 21804.0, 55545.444545554026, 55998.44001559932, 56321.87356252934, 58614.4138558609, 58872.705636471546, 80436.59781701288, 81181.0, 82354.76467852868, 83436.58281708552, 83779.0, 7301.853962920605, 7621.1616722161625, 8256.13310602378, 8316.5425901576, 8458.492490450593, 12459.377031148588, 12931.5473958411, 13053.34733263352, 13164.341782910624, 13454.250947376779, 11117.610883619034, 11189.664310070712, 11345.716357091138, 11537.365444900612, 11767.528339231038, 10052.447115408722, 10726.87367826367, 11441.970222679998, 11587.362695051854, 11793.941030294794, 11471.827922581, 11558.904998378162, 11841.763164736829, 11988.160828741904, 12069.336186509827, 7230.060092187982, 7433.293837085468, 7560.243975602396, 7807.453478256468, 7982.0, 10683.786324273315, 11078.947499987487, 11293.322400655987, 11478.139139564399, 11585.957263846294], "value": 9899.374735361796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.9772903406447, 1541.7456119740243, 1677.6812405642927, 1823.6626224148524, 1854.0822292964983, 8768.78078048049, 9340.57967391468, 9354.345195836295, 9354.719358419241, 9363.48500832454, 8136.267735903767, 8374.58127093646, 8918.509481978488, 9095.363324567266, 9185.90814091859, 2426.0780903256737, 2451.901923923041, 2456.45957889265, 2457.4102215468247, 2458.299384675368, 2423.8909249083836, 2436.878156092197, 2457.5699252630807, 2458.3485376375274, 2465.4452748131616, 2438.3233058113387, 2451.8161137914726, 2464.852108873464, 2465.5315490056923, 2467.2721547143638, 2018.3238615063824, 2417.3714834143248, 2426.4297889995837, 2434.7078350597953, 2451.203358908362, 2418.8427752196226, 2430.3195105370523, 2434.8295619306655, 2435.926922192337, 2439.3901524618846, 9256.07439256069, 9297.953510232473, 9399.953000235024, 9414.340996130273, 9428.434293942317, 5490.670559766427, 5644.6613203207935, 5648.096304591287, 5650.858728531778, 5651.971740141273, 3454.65453454655, 3565.8038807865555, 3612.819359032038, 3643.125649844045, 3677.9264414711556, 949.8100379923974, 1081.6106201767357, 1082.2802836113974, 1093.2456604942565, 1107.5459061784711, 12348.5060597576, 12355.196912200678, 12427.93786031073, 12440.253584784941, 12450.688732781662, 4609.055143695535, 4641.883952901205, 4830.903381932341, 4837.879053023703, 4841.8547443576745, 9633.662821801343, 9648.75878103053, 9650.420974741539, 9652.0, 9656.420614763138, 5505.9724701376235, 5508.64193827408, 5512.5589952803675, 5516.062269414209, 5524.116141417434, 4234.8142520094425, 4446.599806017474, 4613.400257966443, 4742.810287588527, 5150.536451719363, 3891.2217556488818, 3922.2743792398496, 3985.621365970286, 4013.678905687538, 4019.055521952339, 1192.3263356203718, 1199.2145144930084, 1203.8615559210803, 1213.969650758738, 1214.7449035702425, 924.1728652855791, 925.6713866577403, 932.3333816321227, 939.4738946190155, 949.2500924269888, 5814.563907706939, 5839.941600583939, 6040.607360521552, 6195.172721415437, 6215.409536094064, 458.81876658720284, 463.62214794942014, 471.3660127129006, 474.4377912174016, 479.7001873828857, 3945.763254204757, 3969.622885825842, 4018.7789671568344, 4030.818613162356, 4077.3883917412627, 11088.5564577416, 11561.0, 11565.074794016458, 11585.768284634429, 11599.826002609967, 11499.310041397543, 11504.194706370641, 11511.884881151249, 11528.365939873387, 11542.826857597143, 8661.393702440768, 8675.479471231747, 8700.0, 8705.47767133974, 8720.258778003814, 5919.378465261086, 5965.940340596539, 6006.339302676614, 6127.295361033538, 6288.182536270256, 10191.388516689023, 10391.0, 10535.0, 10628.574857005633, 10710.678679639623, 10626.946865265625, 10854.945725271638, 10896.964788345094, 10913.017828395483, 11042.723931901766, 6464.870702586016, 6587.538872278895, 7899.328557072713, 7935.1271360149185, 7937.365010799238, 8615.827683446421, 8677.219050285505, 8678.045415004472, 8689.261412779984, 8697.999730031128, 7763.534187948741, 7823.882641760264, 7833.334166595903, 7887.290143887078, 7889.605519724106, 7631.38948884099, 7668.0, 7717.961410192913, 7779.805504862424, 7804.2585954334245, 6981.825454363681, 7040.366367026787, 7136.785896423115, 7145.928540714526, 7158.713651453883, 4962.156433406401, 5018.0, 5066.442691304027, 5145.125328694206, 5279.0, 4376.781160942004, 5048.343715316985, 5073.670211436245, 5178.145605974972, 5186.144286192887], "value": 4632.985787552498, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.7205440858388, 296.8486072103227, 306.2496882637538, 308.74374269356423, 311.74125475855044, 4448.532904045077, 4706.834451510413, 5119.513646203604, 5354.785808567657, 5498.642588231762, 4536.863894083174, 4541.841035563748, 4790.993891282826, 5347.652402593829, 5461.42655021223, 3353.765236433451, 3690.748643327568, 4210.252745110775, 4217.683673724468, 4228.386883901832, 4211.747295162285, 4213.873583792491, 4222.873313800591, 4274.957250427503, 4275.87172384829, 3798.518577754664, 3986.1828325193264, 4136.544980052192, 4142.730722503043, 4184.958150418503, 4119.588041195886, 4145.875623731293, 4164.146349998243, 4178.477690288714, 4195.2029114468305, 3297.851596678155, 3383.0020144057316, 3607.6211997740384, 3619.791926924574, 3636.181859081712, 5046.823361182378, 5323.946760532383, 5350.3312085989255, 5383.353997520284, 5393.973030134864, 4750.738709370983, 4793.424789025305, 4817.434333841516, 4825.372701548811, 4834.20235661119, 5215.713135777531, 5719.59962802604, 5732.856678583027, 5743.253377060997, 5793.391693872125, 3483.965160348415, 3938.4289278054807, 4010.0776821331256, 4033.7176397652174, 4067.8983025424304, 4773.997460533292, 5337.492938170868, 5535.667859928417, 6013.4888534474185, 6020.127081573191, 3573.141966177573, 4073.287174744467, 4082.5504697181455, 4094.9492627992336, 4115.855945041909, 3483.912902177415, 3696.482492451059, 4149.128682976609, 4164.979175104105, 4181.665466762652, 4440.934175797785, 4472.284434490466, 4665.650076244296, 4800.279958006327, 4821.927671084936, 5735.684537350402, 5768.653880767168, 5777.826665200051, 5779.190913272061, 5797.333306669702, 1415.688548519324, 1416.6741649420485, 1423.6048672300997, 1427.0663251294347, 1429.1849351323967, 839.4627438439404, 871.8970502314577, 891.5230351761905, 898.8629383829461, 917.7751450894574, 4476.597106260387, 4557.794899229542, 5423.579782477268, 5499.690130145348, 5550.555955523549, 5829.358770535237, 5842.707864606753, 5874.206982057368, 5881.05903055509, 5945.464908158287, 5238.633295669343, 5350.411454739976, 5474.260974768435, 5503.954248692736, 5549.0, 2797.69190417906, 3005.729484346419, 3065.049834551296, 3077.738392236685, 3086.2438702518025, 5502.0921547944945, 5690.4025077367105, 6006.3693312201585, 6073.869768557423, 6136.601120927215, 5234.319538460052, 5938.376470470538, 5971.28344598651, 5987.461128498456, 6031.457168854824, 5023.066119543929, 5166.896662066738, 5218.347706536683, 5866.589338746332, 5908.350081491032, 4624.398828152319, 4851.878703032452, 5071.77177027042, 5456.454354564512, 5456.91814622773, 4731.503192164911, 5666.15007748841, 5760.0783874579865, 5778.537716982715, 5785.913211301918, 5705.372409035086, 5943.58394912352, 5956.731947062479, 6005.729742161526, 6035.094735789667, 3898.3372826618975, 4001.7198796083994, 4010.47863777707, 4032.91934161321, 4035.91928161441, 6004.699765011645, 6038.426349496791, 6087.847803804941, 6102.0, 6182.752689892354, 4605.976970115128, 5167.92248116271, 5437.836864893901, 5795.449432303924, 5981.3719559445635, 4575.016371480147, 4979.526944940225, 5714.942850571441, 5753.5834949322225, 5820.970895145497, 5312.601554883322, 5488.286522752017, 5515.200295957144, 5625.381208067028, 5629.493345598916, 4798.8884890648005, 5549.0, 5640.830775076754, 5791.249156650557, 5837.227317024549, 5729.369769325288, 5734.799282025109, 5737.36485101744, 5752.942470575241, 5761.769529218785], "value": 4180.104092044725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.4899697719154, 768.162702654107, 817.55034730898, 829.4359835311988, 863.214474827906, 15847.366105355786, 16103.677926441464, 16898.66202675946, 16939.23773430194, 16957.915210423962, 15292.770608440855, 15335.69328613427, 15499.767503487428, 15506.302216400261, 15510.14694191819, 704.9400800931927, 848.5375470368633, 864.4597126795753, 864.9783755406133, 866.5017614871442, 728.4936968806655, 872.7774417523523, 889.7330800759779, 898.6360523987795, 901.1709227510673, 946.1768261612373, 946.2524605561632, 946.8911075226368, 948.7485816258697, 948.8766460360143, 940.4216406909701, 940.8823897012874, 941.4398432932463, 942.7218970403713, 945.5981207986613, 700.0514303119302, 700.5411455496658, 700.751233312172, 701.3267263427065, 704.718112754897, 15446.691066178613, 15466.845331546652, 15469.613259668486, 15470.767938480933, 15472.303746331441, 13345.532906348328, 14751.0, 14846.1834599097, 14894.329755161043, 15072.472463463835, 13153.408096635674, 13234.27211503367, 13356.532521361705, 13474.663133421645, 13561.86438135616, 4645.907081858378, 4708.576228139484, 4726.480087190406, 4742.525747425264, 4874.731889746063, 122638.38680806628, 123928.0, 127177.0, 131133.34433327868, 132005.99999999904, 11045.558177672803, 11060.281081729661, 11238.7190320241, 11293.887061129284, 11555.306681599131, 21305.691654823717, 21315.573688526452, 21653.78346216549, 21673.349799505726, 21686.34940951774, 7769.53382797034, 7968.6414111363965, 8007.279344858991, 8055.516668999761, 8147.226013528705, 4454.665900057508, 4515.661325400542, 4545.568171023748, 4581.610563102107, 4621.468531118964, 3607.9639203608153, 3758.8496460141837, 3765.679917207013, 3767.9434808477895, 3767.9811600942367, 669.2337273821447, 692.875282449164, 736.7053178728552, 737.0712901743841, 741.6884908338511, 545.7271364317841, 658.3942772649174, 683.3712984054681, 698.3400686351349, 712.5332906945855, 9493.715188544355, 9587.233021358275, 9687.951560242154, 9712.271579631415, 9828.606855725833, 662.5566367080107, 689.4518857508293, 692.6259819697408, 697.8778713725179, 700.3392423422134, 4163.458750362433, 4542.704724192917, 4547.806188439001, 4661.254199328091, 4710.62315014798, 84574.57712711397, 97737.51131244298, 124133.0, 135635.0, 171140.0, 133975.0, 140300.0, 140997.0, 144663.0, 147451.0, 100310.49534256992, 117533.824661754, 119376.20935685972, 119841.0, 124923.0, 7710.961445192738, 8872.068432814462, 8909.465432074096, 8923.0, 8973.0, 57390.7130464345, 59537.0, 60686.39313606807, 64425.67787161034, 65919.0, 67914.64170716458, 81582.0, 83248.0, 88341.67487487821, 94597.05402945883, 13553.390097445836, 13860.168389896638, 13977.371018303997, 13987.020908536304, 14137.858621413654, 14360.640983975483, 14421.495247666282, 14747.55757327239, 14750.492758803994, 15114.54656360311, 14135.939804514908, 14309.356078976263, 14622.634434139232, 14678.339474723449, 15061.548153555412, 10966.451677415938, 13506.527271545448, 13771.586852394445, 13872.514461993782, 14517.927410362881, 13153.145045572008, 13530.729385412433, 14013.719725605228, 14040.227787471787, 14097.506587269396, 11323.71690707706, 11342.432878356214, 11414.600488982845, 11893.345865977108, 12029.218100823418, 10096.596136154472, 10828.566857325934, 11449.42752862337, 11508.654740357802, 11846.348450835287], "value": 8092.431089288085, "unit": "score"}], "name": "dromaeo_css", "value": 7454.271301280215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 114.0, 112.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 105.0, 140.0, 109.0, 109.0, 105.0, 108.0, 137.0, 107.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 180.0, 185.0, 186.0, 184.0, 181.0, 184.0, 184.0, 186.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 88.0, 63.0, 60.0, 57.0, 59.0, 80.0, 57.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 92.0, 68.0, 68.0, 68.0, 92.0, 90.0, 100.0, 123.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 88.0, 89.0, 87.0, 91.0, 88.0, 89.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 96.0, 97.0, 98.0, 97.0, 97.0, 96.0, 99.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 86.0, 86.0, 86.0, 88.0, 94.0, 89.0, 87.0, 92.0], "value": 88.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 52.0, 53.0, 52.0, 56.0, 57.0, 56.0, 56.0, 57.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 78.0, 74.0, 71.0, 67.0, 68.0, 69.0, 68.0, 66.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 81.0, 83.0, 84.0, 80.0, 84.0, 81.0, 86.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 137.0, 136.0, 139.0, 122.0, 147.0, 127.0, 189.0, 150.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 161.0, 160.0, 160.0, 158.0, 160.0, 158.0, 163.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 66.0, 65.0, 66.0, 67.0, 64.0, 65.0, 64.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1414.1, "alertThreshold": 2.0}]} 12:53:01 INFO - Return code: 0 12:53:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:53:01 INFO - # TBPL SUCCESS # 12:53:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:53:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:53:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:53:01 INFO - 2017-01-07 12:53:01.988 screenresolution[1654:9747] starting screenresolution argv=screenresolution get 12:53:01 INFO - 2017-01-07 12:53:01.990 screenresolution[1654:9747] Display 0: 1600x1200x32@60 12:53:01 INFO - 2017-01-07 12:53:01.995 screenresolution[1655:9750] starting screenresolution argv=screenresolution list 12:53:01 INFO - Available Modes on Display 0 12:53:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:53:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:53:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:53:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:53:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:53:02 INFO - Intel Iris: 12:53:02 INFO - Chipset Model: Intel Iris 12:53:02 INFO - Type: GPU 12:53:02 INFO - Bus: Built-In 12:53:02 INFO - VRAM (Dynamic, Max): 1536 MB 12:53:02 INFO - Vendor: Intel (0x8086) 12:53:02 INFO - Device ID: 0x0a2e 12:53:02 INFO - Revision ID: 0x0009 12:53:02 INFO - Displays: 12:53:02 INFO - Display: 12:53:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:53:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:53:02 INFO - Display Serial Number: 1600x1200 60 12:53:02 INFO - Main Display: Yes 12:53:02 INFO - Mirror: Off 12:53:02 INFO - Online: Yes 12:53:02 INFO - Rotation: Supported 12:53:02 INFO - Return code: 0 12:53:02 INFO - Running post-action listener: _resource_record_post_action 12:53:02 INFO - [mozharness: 2017-01-07 20:53:02.222162Z] Finished run-tests step (success) 12:53:02 INFO - Running post-run listener: _resource_record_post_run 12:53:02 INFO - Total resource usage - Wall time: 1196s; CPU: 53.0%; Read bytes: 39044096; Write bytes: 494422528; Read time: 380; Write time: 1824 12:53:02 INFO - TinderboxPrint: CPU usage
53.3% 12:53:02 INFO - TinderboxPrint: I/O read bytes / time
39,044,096 / 380 12:53:02 INFO - TinderboxPrint: I/O write bytes / time
494,422,528 / 1,824 12:53:02 INFO - TinderboxPrint: CPU idle
2,233.0 (46.7%) 12:53:02 INFO - TinderboxPrint: CPU system
958.4 (20.0%) 12:53:02 INFO - TinderboxPrint: CPU user
1,594.3 (33.3%) 12:53:02 INFO - TinderboxPrint: Swap in / out
234,749,952 / 0 12:53:02 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 190611968; Write bytes: 186085376; Read time: 17463; Write time: 363 12:53:02 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 37110784; Write bytes: 297314816; Read time: 360; Write time: 1425 12:53:02 INFO - Running post-run listener: _upload_blobber_files 12:53:02 INFO - Blob upload gear active. 12:53:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:53:02 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 12:53:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:53:03 INFO - (blobuploader) - INFO - Open directory for files ... 12:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:53:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:53:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:53:03 INFO - (blobuploader) - INFO - Done attempting. 12:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:53:05 INFO - (blobuploader) - INFO - Done attempting. 12:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:53:07 INFO - (blobuploader) - INFO - Done attempting. 12:53:07 INFO - (blobuploader) - INFO - Iteration through files over. 12:53:07 INFO - Return code: 0 12:53:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:53:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:53:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a662b8e1aa870ce9e73984c2568758035cda0eea4e3ee489761e4d27938eb524ca700ea7544b2005c40994c9f4363ee1cd78b540eb3cb34fc4e7fee6e52bc4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c606cabf44f7ace295d9082c354fca3cd131c7d7cfc80f109b474f62c756223dea943fb4df36a8f7579f4392aafac7716c72e283ec3671abbc1d711bb9606190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/891b46dd185d069f750296d629aa97e0f86b7f01ddde571103d653830beb8234954a93590376ec2a32fcf0e8941a2fba214125a2831fa889957c2a124832cee4"} 12:53:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:53:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:53:07 INFO - Contents: 12:53:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a662b8e1aa870ce9e73984c2568758035cda0eea4e3ee489761e4d27938eb524ca700ea7544b2005c40994c9f4363ee1cd78b540eb3cb34fc4e7fee6e52bc4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c606cabf44f7ace295d9082c354fca3cd131c7d7cfc80f109b474f62c756223dea943fb4df36a8f7579f4392aafac7716c72e283ec3671abbc1d711bb9606190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/891b46dd185d069f750296d629aa97e0f86b7f01ddde571103d653830beb8234954a93590376ec2a32fcf0e8941a2fba214125a2831fa889957c2a124832cee4"} 12:53:07 INFO - Running post-run listener: copy_logs_to_upload_dir 12:53:07 INFO - Copying logs to upload dir... 12:53:07 INFO - mkdir: /builds/slave/test/build/upload/logs 12:53:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.548594 ========= master_lag: 2.26 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-07 12:53:09.733184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 12:53:09.738236) ========= 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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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/Mozilla-Beta/sha512/8a662b8e1aa870ce9e73984c2568758035cda0eea4e3ee489761e4d27938eb524ca700ea7544b2005c40994c9f4363ee1cd78b540eb3cb34fc4e7fee6e52bc4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c606cabf44f7ace295d9082c354fca3cd131c7d7cfc80f109b474f62c756223dea943fb4df36a8f7579f4392aafac7716c72e283ec3671abbc1d711bb9606190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/891b46dd185d069f750296d629aa97e0f86b7f01ddde571103d653830beb8234954a93590376ec2a32fcf0e8941a2fba214125a2831fa889957c2a124832cee4"} build_url:https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/G3HanhigSX2HXNDNUxcn5w/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a662b8e1aa870ce9e73984c2568758035cda0eea4e3ee489761e4d27938eb524ca700ea7544b2005c40994c9f4363ee1cd78b540eb3cb34fc4e7fee6e52bc4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c606cabf44f7ace295d9082c354fca3cd131c7d7cfc80f109b474f62c756223dea943fb4df36a8f7579f4392aafac7716c72e283ec3671abbc1d711bb9606190", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/891b46dd185d069f750296d629aa97e0f86b7f01ddde571103d653830beb8234954a93590376ec2a32fcf0e8941a2fba214125a2831fa889957c2a124832cee4"}' ========= master_lag: 0.20 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 12:53:09.945900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:53:09.946225) ========= 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.DHghJfhyCH/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.Ma5IaW6N1h/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005377 ========= master_lag: 0.92 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:53:10.876015) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 12:53:10.876323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 12:53:10.876801) ========= ========= Total master_lag: 3.45 =========