builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1479836297.35 results: success (0) buildid: 20161122071851 builduid: e9cdbf3203c34577814e66657bae933e revision: bcb53a7bd34a9c95014c23f21cd380d51be4f06d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.355012) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.355470) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.355743) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.382247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.382616) ========= 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.3pI3pdU197/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.PtdKs137k3/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 --2016-11-22 09:38:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.53M=0.002s 2016-11-22 09:38:17 (7.53 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085463 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.491876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.492196) ========= 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.3pI3pdU197/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.PtdKs137k3/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.033853 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:17.547821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-22 09:38:17.548174) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bcb53a7bd34a9c95014c23f21cd380d51be4f06d --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 bcb53a7bd34a9c95014c23f21cd380d51be4f06d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3pI3pdU197/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.PtdKs137k3/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 2016-11-22 09:38:17,643 truncating revision to first 12 chars 2016-11-22 09:38:17,643 Setting DEBUG logging. 2016-11-22 09:38:17,643 attempt 1/10 2016-11-22 09:38:17,644 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bcb53a7bd34a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 09:38:18,852 unpacking tar archive at: mozilla-beta-bcb53a7bd34a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.501210 ========= master_lag: 1.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-22 09:38:20.607775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:20.608204) ========= script_repo_revision: bcb53a7bd34a9c95014c23f21cd380d51be4f06d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 09:38:20.608719) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-22 09:38:20.608981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-22 09:38:21.842890) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-11-22 09:38:21.843290) ========= /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.3pI3pdU197/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.PtdKs137k3/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:38:22 INFO - MultiFileLogger online at 20161122 09:38:22 in /builds/slave/test 09:38:22 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 09:38:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:38:22 INFO - {'append_to_log': False, 09:38:22 INFO - 'base_work_dir': '/builds/slave/test', 09:38:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:38:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:38:22 INFO - 'branch': 'Mozilla-Beta', 09:38:22 INFO - 'buildbot_json_path': 'buildprops.json', 09:38:22 INFO - 'config_files': ('talos/mac_config.py',), 09:38:22 INFO - 'default_actions': ('clobber', 09:38:22 INFO - 'read-buildbot-config', 09:38:22 INFO - 'download-and-extract', 09:38:22 INFO - 'populate-webroot', 09:38:22 INFO - 'create-virtualenv', 09:38:22 INFO - 'install', 09:38:22 INFO - 'run-tests'), 09:38:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:38:22 INFO - 'download_minidump_stackwalk': True, 09:38:22 INFO - 'download_symbols': 'ondemand', 09:38:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:38:22 INFO - 'tooltool.py': '/tools/tooltool.py', 09:38:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:38:22 INFO - '/tools/misc-python/virtualenv.py')}, 09:38:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:38:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:38:22 INFO - 'installer_path': 'installer.exe', 09:38:22 INFO - 'log_level': 'info', 09:38:22 INFO - 'log_name': 'talos', 09:38:22 INFO - 'log_to_console': True, 09:38:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:38:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:38:22 INFO - 'opt_config_files': (), 09:38:22 INFO - 'pip_index': False, 09:38:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:22 INFO - 'cmd': ('bash', 09:38:22 INFO - '-c', 09:38:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:22 INFO - 'enabled': True, 09:38:22 INFO - 'halt_on_failure': False, 09:38:22 INFO - 'name': 'check_screen_resolution'},), 09:38:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:22 INFO - 'cmd': ('bash', 09:38:22 INFO - '-c', 09:38:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:22 INFO - 'enabled': True, 09:38:22 INFO - 'halt_on_failure': False, 09:38:22 INFO - 'name': 'check_screen_resolution'},), 09:38:22 INFO - 'run_cmd_checks_enabled': True, 09:38:22 INFO - 'sps_profile': False, 09:38:22 INFO - 'sps_profile_interval': 0, 09:38:22 INFO - 'suite': 'dromaeojs', 09:38:22 INFO - 'system_bits': '32', 09:38:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:38:22 INFO - 'title': 't-yosemite-r7-0127', 09:38:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:38:22 INFO - 'use_talos_json': True, 09:38:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:38:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:38:22 INFO - 'work_dir': 'build'} 09:38:22 INFO - [mozharness: 2016-11-22 17:38:22.044316Z] Running clobber step. 09:38:22 INFO - Running pre-action listener: _resource_record_pre_action 09:38:22 INFO - Running main action method: clobber 09:38:22 INFO - rmtree: /builds/slave/test/build 09:38:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:38:24 INFO - Running post-action listener: _resource_record_post_action 09:38:24 INFO - [mozharness: 2016-11-22 17:38:24.563900Z] Finished clobber step (success) 09:38:24 INFO - [mozharness: 2016-11-22 17:38:24.564025Z] Running read-buildbot-config step. 09:38:24 INFO - Running pre-action listener: _resource_record_pre_action 09:38:24 INFO - Running main action method: read_buildbot_config 09:38:24 INFO - Using buildbot properties: 09:38:24 INFO - { 09:38:24 INFO - "project": "", 09:38:24 INFO - "product": "firefox", 09:38:24 INFO - "script_repo_revision": "production", 09:38:24 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:38:24 INFO - "repository": "", 09:38:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:38:24 INFO - "buildid": "20161122071851", 09:38:24 INFO - "pgo_build": "False", 09:38:24 INFO - "basedir": "/builds/slave/test", 09:38:24 INFO - "buildnumber": 0, 09:38:24 INFO - "slavename": "t-yosemite-r7-0127", 09:38:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:38:24 INFO - "platform": "yosemite_r7", 09:38:24 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:38:24 INFO - "branch": "mozilla-beta", 09:38:24 INFO - "repo_path": "releases/mozilla-beta", 09:38:24 INFO - "revision": "bcb53a7bd34a9c95014c23f21cd380d51be4f06d", 09:38:24 INFO - "stage_platform": "macosx64", 09:38:24 INFO - "builduid": "e9cdbf3203c34577814e66657bae933e", 09:38:24 INFO - "slavebuilddir": "test" 09:38:24 INFO - } 09:38:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 09:38:24 INFO - Running post-action listener: _resource_record_post_action 09:38:24 INFO - [mozharness: 2016-11-22 17:38:24.567245Z] Finished read-buildbot-config step (success) 09:38:24 INFO - [mozharness: 2016-11-22 17:38:24.567373Z] Running download-and-extract step. 09:38:24 INFO - Running pre-action listener: _resource_record_pre_action 09:38:24 INFO - Running main action method: download_and_extract 09:38:24 INFO - mkdir: /builds/slave/test/build/tests 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:24 INFO - trying https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 09:38:24 INFO - Downloading https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/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 09:38:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/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 09:38:25 INFO - Downloaded 1165 bytes. 09:38:25 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:38:25 INFO - Using the following test package requirements: 09:38:25 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:38:25 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 09:38:25 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 09:38:25 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:38:25 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 09:38:25 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:38:25 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 09:38:25 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 09:38:25 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 09:38:25 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:38:25 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 09:38:25 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 09:38:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:38:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:38:25 INFO - Fetch https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:38:25 INFO - Expected file size: 16231446 09:38:32 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 09:38:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:38:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:38:32 INFO - Fetch https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:38:33 INFO - Expected file size: 16231446 09:38:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 09:38:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 09:38:39 INFO - Fetch https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 09:38:40 INFO - Expected file size: 14414089 09:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:41 INFO - trying https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:38:41 INFO - Downloading https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 09:38:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/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 09:38:43 INFO - Downloaded 86032510 bytes. 09:38:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:38:43 INFO - mkdir: /builds/slave/test/properties 09:38:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:43 INFO - Contents: 09:38:43 INFO - build_url:https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:38:44 INFO - Running post-action listener: _resource_record_post_action 09:38:44 INFO - Running post-action listener: set_extra_try_arguments 09:38:44 INFO - [mozharness: 2016-11-22 17:38:44.622230Z] Finished download-and-extract step (success) 09:38:44 INFO - [mozharness: 2016-11-22 17:38:44.622333Z] Running populate-webroot step. 09:38:44 INFO - Running pre-action listener: _resource_record_pre_action 09:38:44 INFO - Running main action method: populate_webroot 09:38:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:44 INFO - '--tppagecycles', 09:38:44 INFO - '7'], 09:38:44 INFO - 'tests': ['tsvgm']}}, 09:38:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['damp', 'tps']}, 09:38:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'tests': ['damp', 'tps']}, 09:38:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['dromaeo_dom']}, 09:38:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['basic_compositor_video']}, 09:38:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:38:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['a11yr', 09:38:44 INFO - 'ts_paint', 09:38:44 INFO - 'tpaint', 09:38:44 INFO - 'sessionrestore', 09:38:44 INFO - 'sessionrestore_no_auto_restore', 09:38:44 INFO - 'tabpaint']}, 09:38:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:44 INFO - 'ts_paint', 09:38:44 INFO - 'tpaint', 09:38:44 INFO - 'sessionrestore', 09:38:44 INFO - 'sessionrestore_no_auto_restore', 09:38:44 INFO - 'tabpaint']}, 09:38:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:44 INFO - 'ts_paint', 09:38:44 INFO - 'tpaint', 09:38:44 INFO - 'sessionrestore', 09:38:44 INFO - 'sessionrestore_no_auto_restore', 09:38:44 INFO - 'tabpaint']}, 09:38:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:44 INFO - 'ts_paint', 09:38:44 INFO - 'tpaint', 09:38:44 INFO - 'sessionrestore', 09:38:44 INFO - 'sessionrestore_no_auto_restore', 09:38:44 INFO - 'tabpaint']}, 09:38:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['a11yr', 09:38:44 INFO - 'ts_paint', 09:38:44 INFO - 'tpaint', 09:38:44 INFO - 'sessionrestore', 09:38:44 INFO - 'sessionrestore_no_auto_restore', 09:38:44 INFO - 'tabpaint']}, 09:38:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['a11yr', 09:38:44 INFO - 'ts_paint', 09:38:44 INFO - 'tpaint', 09:38:44 INFO - 'sessionrestore', 09:38:44 INFO - 'sessionrestore_no_auto_restore', 09:38:44 INFO - 'tabpaint']}, 09:38:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['tsvgx', 09:38:44 INFO - 'tsvgr_opacity', 09:38:44 INFO - 'tart', 09:38:44 INFO - 'tscrollx', 09:38:44 INFO - 'cart']}, 09:38:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:44 INFO - 'tsvgr_opacity', 09:38:44 INFO - 'tart', 09:38:44 INFO - 'tscrollx', 09:38:44 INFO - 'cart']}, 09:38:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'talos_options': ['--disable-e10s'], 09:38:44 INFO - 'tests': ['tp5o']}, 09:38:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'tests': ['tp5o']}, 09:38:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'talos_options': ['--disable-e10s', 09:38:44 INFO - '--xperf_path', 09:38:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:44 INFO - 'tests': ['tp5n']}, 09:38:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:44 INFO - 'talos_options': ['--xperf_path', 09:38:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:44 INFO - 'tests': ['tp5n']}}, 09:38:44 INFO - 'talos.zip': {'path': '', 09:38:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:44 INFO - Running post-action listener: _resource_record_post_action 09:38:44 INFO - [mozharness: 2016-11-22 17:38:44.640874Z] Finished populate-webroot step (success) 09:38:44 INFO - [mozharness: 2016-11-22 17:38:44.641001Z] Running create-virtualenv step. 09:38:44 INFO - Running pre-action listener: _resource_record_pre_action 09:38:44 INFO - Running main action method: create_virtualenv 09:38:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:38:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:44 INFO - Using real prefix '/tools/python27' 09:38:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:48 INFO - Installing pip.................done. 09:38:48 INFO - Return code: 0 09:38:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:48 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:38:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:48 INFO - 'HOME': '/Users/cltbld', 09:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:48 INFO - 'LOGNAME': 'cltbld', 09:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:48 INFO - 'MOZ_NO_REMOTE': '1', 09:38:48 INFO - 'NO_EM_RESTART': '1', 09:38:48 INFO - 'PAGER': '/bin/cat', 09:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:48 INFO - 'PWD': '/builds/slave/test', 09:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:48 INFO - 'SHELL': '/bin/bash', 09:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:38:48 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:48 INFO - 'USER': 'cltbld', 09:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:48 INFO - 'XPC_FLAGS': '0x0', 09:38:48 INFO - 'XPC_SERVICE_NAME': '0', 09:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:38:48 INFO - Cleaning up... 09:38:48 INFO - Return code: 0 09:38:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:48 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:38:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:48 INFO - 'HOME': '/Users/cltbld', 09:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:48 INFO - 'LOGNAME': 'cltbld', 09:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:48 INFO - 'MOZ_NO_REMOTE': '1', 09:38:48 INFO - 'NO_EM_RESTART': '1', 09:38:48 INFO - 'PAGER': '/bin/cat', 09:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:48 INFO - 'PWD': '/builds/slave/test', 09:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:48 INFO - 'SHELL': '/bin/bash', 09:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:38:48 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:48 INFO - 'USER': 'cltbld', 09:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:48 INFO - 'XPC_FLAGS': '0x0', 09:38:48 INFO - 'XPC_SERVICE_NAME': '0', 09:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:48 INFO - Downloading/unpacking psutil>=3.1.1 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:52 INFO - Installing collected packages: psutil 09:38:52 INFO - Running setup.py install for psutil 09:38:52 INFO - building 'psutil._psutil_osx' extension 09:38:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:38:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:38:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:38:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:38:53 INFO - building 'psutil._psutil_posix' extension 09:38:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:38:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:38:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:38:53 INFO - ^ 09:38:53 INFO - 1 warning generated. 09:38:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:38:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:53 INFO - Successfully installed psutil 09:38:53 INFO - Cleaning up... 09:38:53 INFO - Return code: 0 09:38:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:53 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:53 INFO - 'HOME': '/Users/cltbld', 09:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:53 INFO - 'LOGNAME': 'cltbld', 09:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:53 INFO - 'MOZ_NO_REMOTE': '1', 09:38:53 INFO - 'NO_EM_RESTART': '1', 09:38:53 INFO - 'PAGER': '/bin/cat', 09:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:53 INFO - 'PWD': '/builds/slave/test', 09:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:53 INFO - 'SHELL': '/bin/bash', 09:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:38:53 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:53 INFO - 'USER': 'cltbld', 09:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:53 INFO - 'XPC_FLAGS': '0x0', 09:38:53 INFO - 'XPC_SERVICE_NAME': '0', 09:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:56 INFO - Installing collected packages: mozsystemmonitor 09:38:56 INFO - Running setup.py install for mozsystemmonitor 09:38:56 INFO - Successfully installed mozsystemmonitor 09:38:56 INFO - Cleaning up... 09:38:56 INFO - Return code: 0 09:38:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:56 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:56 INFO - 'HOME': '/Users/cltbld', 09:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:56 INFO - 'LOGNAME': 'cltbld', 09:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:56 INFO - 'MOZ_NO_REMOTE': '1', 09:38:56 INFO - 'NO_EM_RESTART': '1', 09:38:56 INFO - 'PAGER': '/bin/cat', 09:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:56 INFO - 'PWD': '/builds/slave/test', 09:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:56 INFO - 'SHELL': '/bin/bash', 09:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:38:56 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:56 INFO - 'USER': 'cltbld', 09:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:56 INFO - 'XPC_FLAGS': '0x0', 09:38:56 INFO - 'XPC_SERVICE_NAME': '0', 09:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:59 INFO - warning: no files found matching '*.txt' 09:38:59 INFO - no previously-included directories found matching 'build' 09:38:59 INFO - no previously-included directories found matching 'dist' 09:38:59 INFO - no previously-included directories found matching '.git*' 09:38:59 INFO - Installing collected packages: jsonschema, functools32 09:38:59 INFO - Running setup.py install for functools32 09:38:59 INFO - warning: no files found matching '*.txt' 09:38:59 INFO - no previously-included directories found matching 'build' 09:38:59 INFO - no previously-included directories found matching 'dist' 09:38:59 INFO - no previously-included directories found matching '.git*' 09:38:59 INFO - Successfully installed jsonschema functools32 09:38:59 INFO - Cleaning up... 09:39:00 INFO - Return code: 0 09:39:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:00 INFO - 'HOME': '/Users/cltbld', 09:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:00 INFO - 'LOGNAME': 'cltbld', 09:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:00 INFO - 'MOZ_NO_REMOTE': '1', 09:39:00 INFO - 'NO_EM_RESTART': '1', 09:39:00 INFO - 'PAGER': '/bin/cat', 09:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:00 INFO - 'PWD': '/builds/slave/test', 09:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:00 INFO - 'SHELL': '/bin/bash', 09:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:00 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:00 INFO - 'USER': 'cltbld', 09:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:00 INFO - 'XPC_FLAGS': '0x0', 09:39:00 INFO - 'XPC_SERVICE_NAME': '0', 09:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:39:00 INFO - Cleaning up... 09:39:00 INFO - Return code: 0 09:39:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:00 INFO - 'HOME': '/Users/cltbld', 09:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:00 INFO - 'LOGNAME': 'cltbld', 09:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:00 INFO - 'MOZ_NO_REMOTE': '1', 09:39:00 INFO - 'NO_EM_RESTART': '1', 09:39:00 INFO - 'PAGER': '/bin/cat', 09:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:00 INFO - 'PWD': '/builds/slave/test', 09:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:00 INFO - 'SHELL': '/bin/bash', 09:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:00 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:00 INFO - 'USER': 'cltbld', 09:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:00 INFO - 'XPC_FLAGS': '0x0', 09:39:00 INFO - 'XPC_SERVICE_NAME': '0', 09:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:03 INFO - Downloading blobuploader-1.2.4.tar.gz 09:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:39:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:39:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:04 INFO - Downloading docopt-0.6.1.tar.gz 09:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:39:04 INFO - Installing collected packages: blobuploader, requests, docopt 09:39:04 INFO - Running setup.py install for blobuploader 09:39:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:39:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:39:04 INFO - Running setup.py install for requests 09:39:04 INFO - Running setup.py install for docopt 09:39:05 INFO - Successfully installed blobuploader requests docopt 09:39:05 INFO - Cleaning up... 09:39:05 INFO - Return code: 0 09:39:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39: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')]} 09:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39: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')]} 09:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:05 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:39:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:05 INFO - 'HOME': '/Users/cltbld', 09:39:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:05 INFO - 'LOGNAME': 'cltbld', 09:39:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:05 INFO - 'MOZ_NO_REMOTE': '1', 09:39:05 INFO - 'NO_EM_RESTART': '1', 09:39:05 INFO - 'PAGER': '/bin/cat', 09:39:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:05 INFO - 'PWD': '/builds/slave/test', 09:39:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:05 INFO - 'SHELL': '/bin/bash', 09:39:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:05 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:05 INFO - 'USER': 'cltbld', 09:39:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:05 INFO - 'XPC_FLAGS': '0x0', 09:39:05 INFO - 'XPC_SERVICE_NAME': '0', 09:39:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-UW1dPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-awZua2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-XOYITB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-7MwTcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HJrNs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-6llJfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-A82MzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-T0EJ0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-hkHG7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-s4JJrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5v7K4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-cOglVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-zeqTtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-bI0Wvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-SY09Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:07 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-XyWJSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:07 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Z6P5r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:39:07 INFO - Running setup.py install for manifestparser 09:39:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:39:07 INFO - Running setup.py install for mozcrash 09:39:07 INFO - Running setup.py install for mozdebug 09:39:07 INFO - Running setup.py install for mozdevice 09:39:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:39:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:39:07 INFO - Running setup.py install for mozfile 09:39:07 INFO - Running setup.py install for mozhttpd 09:39:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Running setup.py install for mozinfo 09:39:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Running setup.py install for mozInstall 09:39:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Running setup.py install for mozleak 09:39:08 INFO - Running setup.py install for mozlog 09:39:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Running setup.py install for moznetwork 09:39:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:39:08 INFO - Running setup.py install for mozprocess 09:39:09 INFO - Running setup.py install for mozprofile 09:39:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:39:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:39:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:39:09 INFO - Running setup.py install for mozrunner 09:39:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:39:09 INFO - Running setup.py install for mozscreenshot 09:39:09 INFO - Running setup.py install for moztest 09:39:09 INFO - Running setup.py install for mozversion 09:39:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:39:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:39:09 INFO - Cleaning up... 09:39:09 INFO - Return code: 0 09:39:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:09 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:09 INFO - 'HOME': '/Users/cltbld', 09:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:09 INFO - 'LOGNAME': 'cltbld', 09:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:09 INFO - 'MOZ_NO_REMOTE': '1', 09:39:09 INFO - 'NO_EM_RESTART': '1', 09:39:09 INFO - 'PAGER': '/bin/cat', 09:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:09 INFO - 'PWD': '/builds/slave/test', 09:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:09 INFO - 'SHELL': '/bin/bash', 09:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:09 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:09 INFO - 'USER': 'cltbld', 09:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:09 INFO - 'XPC_FLAGS': '0x0', 09:39:09 INFO - 'XPC_SERVICE_NAME': '0', 09:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-98tgFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9qV9B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:10 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)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-E2Oeo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-q5PLMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:10 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)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-8kwmwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-wbZBe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-W9Df8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-legkN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:10 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Vs3oWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-OVrNlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-PX8ILn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-CJ3ojw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:11 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)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-xnjWvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-6tK5iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:11 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)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Z1gDvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-KO3oXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:11 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)) 09:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-WHYq_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:39:11 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)) 09:39:11 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)) 09:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:39:11 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)) 09:39:11 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)) 09:39:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:14 INFO - Downloading blessings-1.6.tar.gz 09:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:39:14 INFO - Installing collected packages: blessings 09:39:14 INFO - Running setup.py install for blessings 09:39:14 INFO - Successfully installed blessings 09:39:14 INFO - Cleaning up... 09:39:14 INFO - Return code: 0 09:39:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:39:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:14 INFO - Reading from file tmpfile_stdout 09:39:14 INFO - Current package versions: 09:39:14 INFO - blessings == 1.6 09:39:14 INFO - blobuploader == 1.2.4 09:39:14 INFO - docopt == 0.6.1 09:39:14 INFO - functools32 == 3.2.3-2 09:39:14 INFO - jsonschema == 2.5.1 09:39:14 INFO - manifestparser == 1.1 09:39:14 INFO - mozInstall == 1.12 09:39:14 INFO - mozcrash == 0.17 09:39:14 INFO - mozdebug == 0.1 09:39:14 INFO - mozdevice == 0.48 09:39:14 INFO - mozfile == 1.2 09:39:14 INFO - mozhttpd == 0.7 09:39:14 INFO - mozinfo == 0.9 09:39:14 INFO - mozleak == 0.1 09:39:14 INFO - mozlog == 3.4 09:39:14 INFO - moznetwork == 0.27 09:39:14 INFO - mozprocess == 0.23 09:39:14 INFO - mozprofile == 0.28 09:39:14 INFO - mozrunner == 6.12 09:39:14 INFO - mozscreenshot == 0.1 09:39:14 INFO - mozsystemmonitor == 0.3 09:39:14 INFO - moztest == 0.7 09:39:14 INFO - mozversion == 1.4 09:39:14 INFO - psutil == 3.1.1 09:39:14 INFO - requests == 1.2.3 09:39:14 INFO - wsgiref == 0.1.2 09:39:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:15 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 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:15 INFO - 'HOME': '/Users/cltbld', 09:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:15 INFO - 'LOGNAME': 'cltbld', 09:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:15 INFO - 'MOZ_NO_REMOTE': '1', 09:39:15 INFO - 'NO_EM_RESTART': '1', 09:39:15 INFO - 'PAGER': '/bin/cat', 09:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:15 INFO - 'PWD': '/builds/slave/test', 09:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:15 INFO - 'SHELL': '/bin/bash', 09:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:15 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:15 INFO - 'USER': 'cltbld', 09:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:15 INFO - 'XPC_FLAGS': '0x0', 09:39:15 INFO - 'XPC_SERVICE_NAME': '0', 09:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:39:15 INFO - Cleaning up... 09:39:15 INFO - Return code: 0 09:39:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109639570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa741465540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, '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.3pI3pdU197/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.PtdKs137k3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:15 INFO - 'HOME': '/Users/cltbld', 09:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:15 INFO - 'LOGNAME': 'cltbld', 09:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:15 INFO - 'MOZ_NO_REMOTE': '1', 09:39:15 INFO - 'NO_EM_RESTART': '1', 09:39:15 INFO - 'PAGER': '/bin/cat', 09:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:15 INFO - 'PWD': '/builds/slave/test', 09:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:15 INFO - 'SHELL': '/bin/bash', 09:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:15 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:15 INFO - 'USER': 'cltbld', 09:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:15 INFO - 'XPC_FLAGS': '0x0', 09:39:15 INFO - 'XPC_SERVICE_NAME': '0', 09:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:39:15 INFO - Cleaning up... 09:39:15 INFO - Return code: 0 09:39:15 INFO - Running post-action listener: _resource_record_post_action 09:39:15 INFO - Running post-action listener: _start_resource_monitoring 09:39:15 INFO - Starting resource monitoring. 09:39:15 INFO - [mozharness: 2016-11-22 17:39:15.861337Z] Finished create-virtualenv step (success) 09:39:15 INFO - [mozharness: 2016-11-22 17:39:15.862254Z] Running install step. 09:39:15 INFO - Running pre-action listener: _resource_record_pre_action 09:39:15 INFO - Running main action method: install 09:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:16 INFO - Reading from file tmpfile_stdout 09:39:16 INFO - Detecting whether we're running mozinstall >=1.0... 09:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:39:16 INFO - Reading from file tmpfile_stdout 09:39:16 INFO - Output received: 09:39:16 INFO - Usage: mozinstall [options] installer 09:39:16 INFO - Options: 09:39:16 INFO - -h, --help show this help message and exit 09:39:16 INFO - -d DEST, --destination=DEST 09:39:16 INFO - Directory to install application into. [default: 09:39:16 INFO - "/builds/slave/test"] 09:39:16 INFO - --app=APP Application being installed. [default: firefox] 09:39:16 INFO - mkdir: /builds/slave/test/build/application 09:39:16 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'] 09:39:16 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 09:39:38 INFO - Reading from file tmpfile_stdout 09:39:38 INFO - Output received: 09:39:38 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:39:38 INFO - Running post-action listener: _resource_record_post_action 09:39:38 INFO - [mozharness: 2016-11-22 17:39:38.310754Z] Finished install step (success) 09:39:38 INFO - [mozharness: 2016-11-22 17:39:38.310885Z] Running run-tests step. 09:39:38 INFO - Running pre-action listener: _resource_record_pre_action 09:39:38 INFO - Running main action method: run_tests 09:39:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:38 INFO - 2016-11-22 09:39:38.325 screenresolution[1623:7452] starting screenresolution argv=screenresolution get 09:39:38 INFO - 2016-11-22 09:39:38.327 screenresolution[1623:7452] Display 0: 1600x1200x32@60 09:39:38 INFO - 2016-11-22 09:39:38.333 screenresolution[1624:7454] starting screenresolution argv=screenresolution list 09:39:38 INFO - Available Modes on Display 0 09:39:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:38 INFO - Intel Iris: 09:39:38 INFO - Chipset Model: Intel Iris 09:39:38 INFO - Type: GPU 09:39:38 INFO - Bus: Built-In 09:39:38 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:38 INFO - Vendor: Intel (0x8086) 09:39:38 INFO - Device ID: 0x0a2e 09:39:38 INFO - Revision ID: 0x0009 09:39:38 INFO - Displays: 09:39:38 INFO - Display: 09:39:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:38 INFO - Display Serial Number: 1600x1200 60 09:39:38 INFO - Main Display: Yes 09:39:38 INFO - Mirror: Off 09:39:38 INFO - Online: Yes 09:39:38 INFO - Rotation: Supported 09:39:38 INFO - Return code: 0 09:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:38 INFO - Python 2.7.3 09:39:38 INFO - Return code: 0 09:39:38 INFO - grabbing minidump binary from tooltool 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094d7d30>, '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 0x10933ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094cfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:39:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:39:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:39:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:38 INFO - Return code: 0 09:39:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:39:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:39:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 09:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3pI3pdU197/Render', 09:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:38 INFO - 'HOME': '/Users/cltbld', 09:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:38 INFO - 'LOGNAME': 'cltbld', 09:39:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:38 INFO - 'MOZ_NO_REMOTE': '1', 09:39:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:38 INFO - 'NO_EM_RESTART': '1', 09:39:38 INFO - 'PAGER': '/bin/cat', 09:39:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:38 INFO - 'PWD': '/builds/slave/test', 09:39:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:38 INFO - 'SHELL': '/bin/bash', 09:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PtdKs137k3/Listeners', 09:39:38 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 09:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:38 INFO - 'USER': 'cltbld', 09:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:38 INFO - 'XPC_FLAGS': '0x0', 09:39:38 INFO - 'XPC_SERVICE_NAME': '0', 09:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 09:39:38 INFO - mozversion application_buildid: 20161122071851 09:39:38 INFO - mozversion application_changeset: bcb53a7bd34a9c95014c23f21cd380d51be4f06d 09:39:38 INFO - mozversion application_display_name: Firefox 09:39:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:38 INFO - mozversion application_name: Firefox 09:39:38 INFO - mozversion application_remotingname: firefox 09:39:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:39:38 INFO - mozversion application_vendor: Mozilla 09:39:38 INFO - mozversion application_version: 51.0 09:39:38 INFO - mozversion platform_buildid: 20161122071851 09:39:38 INFO - mozversion platform_changeset: bcb53a7bd34a9c95014c23f21cd380d51be4f06d 09:39:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:39:38 INFO - mozversion platform_version: 51.0 09:39:38 INFO - using testdate: 1479836378 09:39:38 INFO - actual date: 1479836378 09:39:38 INFO - starting webserver on 'localhost:49240' 09:39:38 INFO - SUITE-START | Running 2 tests 09:39:38 INFO - TEST-START | dromaeo_css 09:39:38 INFO - operating with platform_type : mac_ 09:39:38 INFO - Initialising browser for dromaeo_css test... 09:39:38 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpYcVcHp/profile http://localhost:49240/getInfo.html) 09:39:41 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:39:41 INFO - PROCESS | 1634 | colorDepth:24 09:39:41 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:39:41 INFO - PROCESS | 1634 | __metrics 09:39:41 INFO - PROCESS | 1634 | 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" 09:39:41 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:41 INFO - TEST-INFO | 1634: exit 0 09:39:41 INFO - Browser initialized. 09:39:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:41 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpYcVcHp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:53 INFO - PROCESS | 1637 | RSS: Main: 168292352 09:39:53 INFO - PROCESS | 1637 | 09:40:45 INFO - PROCESS | 1637 | 1479836445738 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] 09:42:56 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:42:57 INFO - PROCESS | 1637 | RSS: Main: 323198976 09:42:57 INFO - PROCESS | 1637 | 09:46:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:46:01 INFO - PROCESS | 1637 | RSS: Main: 304218112 09:46:01 INFO - PROCESS | 1637 | 09:49:03 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:49:04 INFO - PROCESS | 1637 | RSS: Main: 269811712 09:49:04 INFO - PROCESS | 1637 | 09:52:06 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:52:07 INFO - PROCESS | 1637 | RSS: Main: 269561856 09:52:07 INFO - PROCESS | 1637 | 09:55:10 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:55:10 INFO - PROCESS | 1637 | RSS: Main: 259543040 09:55:10 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:58:13 INFO - PROCESS | 1637 | RSS: Main: 344748032 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | __start_tp_report 09:58:13 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 09:58:13 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 09:58:13 INFO - PROCESS | 1637 | |i|pagename|runs| 09:58:13 INFO - PROCESS | 1637 | |0;dojo.html;3496.510488531606;3498.3702933471977;3533;3539.681428671419;3579.67782899539;5218.21726740989;5553.750081246354;5831.591788574801;5903.055511118217;5933.940660593393;4775.426948766144;5448.536874365674;5857.912131318034;5916.911246331309;5924.940750592493;5268.104422248227;5284.920726189111;5304.575633949294;5342.839714808562;5355.169948657965;5228.215767634847;5243.449437809032;5244.475552444763;5398.217258497515;5403.891922161574;5304.151335786275;5304.469553044703;5314.096603577382;5328.760205790749;5335.1730481775385;5342.866428339303;5346.599005074635;5404.864878378033;5405.918911216335;5412.972935135338;5194.324737784102;5260.631755777097;5310.017646735367;5337.11937530307;5359.758810853521;5777.220075289824;5823.6505809651135;5825.446582574648;5831.183634291203;5873.2952045754355;6280.057991301342;6327.5570710050315;6337.524685648548;6351.2060992375955;6352.872942541169;5205.417945072222;6286.528510361695;6306.653134077624;6310.936890631081;6345.206849143857;5830.7667693292215;5873.911891321633;5889.793857214977;5937.554683398763;5950.583459157818;6338.291725091162;6396.360363963567;6406.743730250831;6418.679066046679;6427.068075129174;6290.370962903673;6294.779682711083;6311.526635502356;6318.052292156121;6319.273283572448;5180.663256888291;6240.687965601702;6264.342244064399;6312;6318.147050148171;6367.4906007519285;6380.7128679209545;6382.521310901701;6383.71273292703;6407.807765767035;6297.559170857996;6352.301246862841;6358;6373.362663733589;6388.361163883575;4989.3264409305;5032.270320803536;5044.974775126174;5048.167052436307;5053.292539044537;3875.205582855508;4029.81865816039;4540.432445944257;4580.587747102777;4634;5947.464728174485;6015.157877897098;6020.819375418744;6024.337322894478;6027.48766354856;6249.0626406039455;6272;6281.7173227204885;6294.307626161119;6295.96852015737;5543.168524721244;5811.128330750336;5933.436323549256;5988.520918326523;6077.088436734525;5494.560435165177;5533.501984821385;5537.446255374431;5545.77816887328;5559.054960656698;6489.513286503437;6494.967525162344;6498.350164983463;6505.41451269388;6509.804705858832;5289.7355132244;6253.499720022478;6275.497960163085;6281.9948808190475;6315.873682526416;6356.2054743157105;6389.7763578274535;6398.0402939559435;6411.4229719325485;6500.3174666659315;6282.811515654537;6289.276733175743;6316.936830631635;6323.9683801580695;6327.114204011534;5295.3116094908955;6251.3123556407845;6293.087502312231;6302.779402720883;6306.873862522815;6083.726232319467;6142.078688196806;6157.199564056644;6158.137860699416;6209.472194863487;6312.842178945563;6336.778212762531;6342.619442833445;6346.936530634819;6347.460465860454;6142.877142457215;6184.226971628546;6185.659788711664;6193.504519638328;6196.566240363131;6114.571104522845;6164.044573091178;6201.875962480635;6311.810645680637;6323.019931910561;6075.513958883367;6153.046277826944;6179.567430279837;6179.907301390573;6187.876242475035 09:58:13 INFO - PROCESS | 1637 | |1;ext.html;535.7000079955225;545.5962587685111;551.1099574187683;557.3952261795952;564.7966731976489;32541.186470338253;43245.91885202871;43999.78000110003;44548.77725611372;46428;45695;45847;45888;45903.31145032827;46180.76909615456;10018.998100189996;10150.74623134424;10203.795924081514;10216.233782466345;10223.744406389826;9405.576749046275;9623.422594644344;9629.759256018586;9712.223022158245;9719.416834989923;12391.566295179671;12642.115051946368;12652.114351995364;12753.426095825664;12787.680307992328;12565.685857853536;12653.240805551697;12662.683432914158;12666.683332916704;12675.492980280778;12383.504659813596;12498.25010499373;12501.812472812915;12506.062045346636;12518.81221781674;42965;43028.56971430277;43090.35364469504;43212.783936080436;43270.56729432697;3445.0870519312402;3631.382664946965;3718.1448266898765;4054.513458384983;4153.460050193456;30898.53652195219;31445.37109257825;31863.84068079668;33467.6653233467;33819;3428.211511352403;3476.6001909780193;3536.5756109266795;3554.964450355489;3595.0473124621994;527126;531185;534174;534589;534736;35583;36442.8177859109;36508.90473285806;37371.25257494835;67053;37911.81044094762;38036;38094.666686665834;38099.61900381016;38202.42696359557;15951.920240398724;16116.952398094087;16153.192340382933;16168.67662646764;16172.676546469005;13483.325833708275;13563.728725425437;13587.252701101534;13599.864001360058;13672.52146174879;7633.847323053508;7745.148033716287;7780.02749656293;8057.637406316612;8202.753917382488;3147.905562833119;3413.8805141819917;3422.6748458896363;3432.811195384228;3451;986.5215370545279;988.1255089245925;1039.9964034706393;1073.8603981482356;1140.8591408591408;31728.04815855528;33608.49587256193;34411.13972150716;34749;34890;1051.3954476176077;1133.8525991621202;1165.2309475746135;1166.2128063557066;1173.7065733566608;13486.527971521145;16485.83514164867;16541.6691666166;16596.548381616645;17116.14419279031;727439;736083;737099;738752;738883;63491;63684.68157659182;63694.36305636884;63881;63944;1456.097219723778;1457.7084583083426;1493.0519120358433;1502.3389708527993;1509.2453773113443;24085.610281997535;31018.914337998063;31232.68767312298;31715.524267136472;31838.522422163223;55543.166852498056;56269.71865140648;56271.71864140653;56409.43590564042;56688.43311566996;648750;653091;653930;654934;655804;24979.625305620066;27025.86487067552;28337.574936375557;28491.572626411034;28910;49254.75372623114;49459.50540494693;50294.49705502898;50321.49678503168;50525.98948021093;40530;41512.58487415208;42147;42497;43603.345949810144;41099.7945010273;41259.7937010313;41279.66563510788;42358.36462453004;42689.359659605754;40068.59931400649;40713.38929915994;41254.9686257834;41313;41444.585554144076;31432.214194645316;36018.857936215216;36121.63878361183;36121.63878361288;36378.4543231857;21270.36188914335;26971.325716857235;26989.325266868484;26999.460010800067;27176.845475263206 09:58:13 INFO - PROCESS | 1637 | |2;jquery.html;377.84319507404444;381.4583291725749;390;394.4891365681442;413.4212103055722;19734.703979440303;21945.780542194574;22667.206647767332;22694.31917042487;22926.88536557317;18092.63814723705;20745.17019319229;21419.14323427061;21640.350789476302;22494;5264.657797243177;5554.833354999357;5614.157876318544;5626.12795016771;5632.577556683244;4116.567760385161;4205.642520385771;4206.348016057516;5497.202905578689;5606.383297837255;5926.822195334147;6184.56708030438;6222.688865556726;6246.063090536433;6253.343398943114;6241.407066328714;6454.967725161391;6908.723651053977;6968.825779355506;6970.79087627372;5872.911906321408;6015.819525414245;6152.477039451652;6152.78465253714;6161.784337548209;13291.401886915111;13372.197668139943;13497.662558436019;13501.98735094862;13513.797293040612;2123.7132987046707;2305.4582173189287;2327.5577640248466;2403.951920961571;2799.762020228283;10784.946075269652;12252.326122063283;14062.859371406257;15667.451639192574;16251.83748162515;1695.3303445139225;1954;2012.4767560434245;2041.2651445479764;2057.794220577945;119400;121240;125320;127571;129727.99999999905;9382.671606493877;11777.764444711058;11795.997340226006;11935.104867134825;12056.457459414347;15168.469103581321;15188.01277916932;15205;15208.467703630542;15233.00985435943;8776.210141087333;9002.729918102468;9005.189532942066;9138.81722365549;9161.312901532412;8955.776105597412;8962.462252264884;8966.372353935292;9048.954755226181;9128.26973842091;4664.346991421204;4668.393108895822;4680.227762419231;4683.437987441461;4740.905181896343;1731.37670438642;1843.5667618109733;1852.2961274715522;1928.9421317360502;1982.0971867007545;681.9454443644496;843.4517563583597;892.6875593542338;931.4364809290417;1037.5175543372368;20510.487237818874;22068.88965555162;22206.333809985725;22223.888805559658;22275.3317400475;962.6005207838668;981.2640519610293;1012.3015119567475;1019.7501612104934;1071.2340676416243;9299.860502092473;11429.428528573539;11636.976600371192;11802.704932376588;11847.348395838138;618894;622692;632351;647980;650090;81972;84224;125222;132618.67381326063;133827;992.2558081438921;1024;1034.7516596017051;1037.159042699215;1040.0743338428804;15347.539573812803;16019.439319623758;18450.169742361893;20001.399958001282;20056.097475613926;55153.99999999839;55396.72301638627;56728.432715672316;56831.98178404466;56943;82306.3538729234;83151;85299;86488;87751;7282.563046217244;7497.10034795828;7593.962030189814;8031.799205019921;8491.830163396575;9089.818323617887;12520.874791252336;13381.598552043455;13820.516281930084;13960.441582336998;11510.48202830858;11658.76682466363;11951.342676152548;12027.699307517381;12133.332666703063;10815.080718139046;11402.429878506207;11484.82772758426;12005.939970300093;12030.63908082759;11366.920142586441;11592.188546801643;11663.358515281401;11691.766164676828;12033.338166400932;7412.518186317872;7593.620318983918;7614.238576142341;7628.084629844232;7831.921680783119;9764.511774411392;11203.655561332693;11575.363355015219;11830.645080647595;11854.466549005489 09:58:13 INFO - PROCESS | 1637 | |3;mootools.html;1446.6021843992903;1572.1117568573754;1767.5846176148602;1927.5084853362407;1929.7780755213146;8844.601992910319;9373.81252374952;9396.436213827157;9409.576569054392;9411.952940235298;8353.415260931737;8607.354448416363;8625.698100566482;8901.243394311476;9213.493257870814;2057.917683292674;2440.1215562397524;2495.026939493599;2497.2258599834017;2507.3104896153613;2459.9262022139364;2475.5420247254224;2487.9751202487923;2492.850428974258;2494.650748895156;2472.8269021168526;2477.1082410332265;2485.875706214691;2488.1167185649106;2501.5122051200024;2440.7681270279295;2455.8280920335583;2467.8519288842726;2468.358226861029;2470.6047032474717;2076.5535409886797;2464.1006032798077;2468.173551571842;2470.258922323296;2473.888675009611;9246.121618446237;9257.398269112486;9338.626454941794;9367.718968430958;9371.109744574254;5327.600429967729;5391.3799913009725;5501.202325662796;5501.477359650867;5506.421825708283;3664.9450258246147;3731.5149030626117;3820.751651142667;3829.0618798394294;3849.2301539692176;1097.2703152403642;1124.1624989382963;1126.8140756775122;1146.868110167333;1148.311013391958;12276.75446491074;12358.320292383914;12359.505619775287;12363.814542781865;12389.752204955941;4831.903361932742;4833.661643684908;4896.436909755424;5010.69935803853;5030.4215015272985;10500.474976251156;10501.9474902625;10507.211959102944;10513.9474302628;10521.684349469375;5576.525995290364;5594.300712410949;5605.747741351649;5608.663480191201;5612.045952188057;4527.705699129546;4836.153673107192;5075.56857667095;5130.05094057593;5135.794568217304;4092.7953602319767;4095.365218391154;4182.142660754538;4200.7479551227025;4222.218889505451;1128.8758236593967;1297.5912587534897;1309.4369421148997;1309.5482058689736;1324.0466863858007;922.5802259971697;948.9762755931157;951.9904800951902;957.3633533700012;1005.5776573839005;5856.443637854397;6051.122587224828;6081.604695694766;6145.078238264206;6254.718537665816;444.4999375702335;446.41296694846176;455.2033940603943;457.6498978281617;461.3448902558375;4081.8163182656854;4129.339305711072;4131.5248746393945;4147.3778933160265;4223.788810559459;11135;11511.366874821964;11535.942320288344;11576.942115289537;11589.188756787113;11409.828852567218;11432.199746017699;11440.256383335056;11450.427478626036;11454.312741235386;8222.62998165084;8553.486790792573;8589.828203435896;8604.956975215084;8776.034636190014;6026.246719160105;6029.969850150721;6119.265688117276;6259.96870015647;6284.905726414016;10298.330608510423;10477.266591338534;10712.625058122929;10730.302530335503;10745.355278683304;10625.521851516545;10627.681169564925;10764.300320479144;10816.783664326827;10922.962318579723;7299.160596531467;7503.474756766974;7699.961500192463;7931;8083.070446898682;8735.301175905784;8884.422512536665;8919.241864441596;8920.152585504367;8931.508767017876;7347.375473084848;7453.515521491086;7556.6977320908745;7576.772696819105;8097.2307630774985;7897.565633890191;7927.682892684459;8070.991126109237;8103.189681031849;8150.225728555778;7086.610236436839;7212.927870721435;7218.27817218295;7222.313880181375;7226.819329516804;5241.161414173714;5315.973420133029;5357.089294819968;5377.973110134424;5506.311711036121;5298.364196296469;5408.486193811582;5440.374356948843;5516.531094856982;5542.7782888685615 09:58:13 INFO - PROCESS | 1637 | |4;prototype.html;299.88754217168565;304.2651995431034;308.68822489285816;313.8524893300148;317.90939582219073;5259;5544.223808666785;5566.248556444879;5611.775528978836;5621.718914054296;4597.471290801559;4759.976200119003;4775.713457192571;5258.211268309756;5529.585281103923;3569.109021692431;4169.62473377396;4184.205001049791;4194.203101410722;4231.280682284004;3682.39240525313;3844.0966372902344;4141.295979683446;4172.541020487759;4242.97878510607;3255.641879393265;3968.6825053995613;3977.164795392971;4162.875113746592;4188.727732697365;4012.996750812297;4093.3450647896493;4162.250794856925;4190.904547726137;4194.30793919003;3609.837557309901;3630.7276954228273;3634.345817752803;3643.562772467321;3646;4447.777611119431;5117.7185254810975;5350.652207606493;5383.596230282745;5389.51494365509;4326.718763280408;4388.539203383662;4769.689970151963;4847.75761211938;4881.609471242327;5473.452654734535;5522.646951496909;5743.655380677173;5812.285375814145;5831.358550559435;3867.245887052026;3876.3991581304945;4044.312466880637;4048.6761059115493;4052.2705912935658;5056.949430505685;5678.65582829136;5958.404159584007;5974.3726908674835;5990.490808281258;3304.339132173575;4055.310597198424;4055.9391609125887;4089.9386509202386;4199.202151591188;3625.3474374612297;3831.700979412386;3906.43356713275;4198.328267477189;4213.810378532973;4413.6248418884115;4713.74074425903;4740.502247263988;4749.691270067435;4802.447718512346;4801.51984801524;4822.469528351879;5743.396943320975;5764.596478246566;5782.566307526952;1391.4781956766212;1418.4964337660188;1423.6404233956546;1430.5493769462587;1430.6208854653526;872.8079822439054;881.6164968238836;916.4867674102524;941.4022095968973;962.9807403851884;4706.858794236178;4807.879803004883;4973.626977976666;5367.1412573988755;5368.3826359968325;5797.739101740432;5830.414295208417;5916.822495325147;5971.775785963867;5984.371640977722;4704.835330763407;4705.576498115186;4925.532074452921;5380.381256155514;5409.837704868782;3033.9848300758354;3056.587360706321;3060.296131889633;3062.31098002952;3065.632124145117;4590.449146102423;5246.947530524722;5903.258538731085;6062.545309101835;6070.2108725865355;4924.162892308316;5424.267723857308;5583.720813959286;5936.881262374728;5938.317093534299;5476.123820188751;5641;5985.6955072296605;6048.939510604838;6075.544334174866;5658.943410565842;5835.387284335084;5842.766289348378;5843.328017277898;5947;5569.799929028092;5821;5840.649561026352;5843.179377013896;5851.882962340815;5776;5782.681952492653;5974.536238621566;6095;6175.44421002112;3390.406678831145;3733.775973441602;4048.959510404858;4066.268071747114;4074.5721699221153;5098.898022039612;5644.5286149018875;5728.083506639085;5854.297484301911;5891.820026590189;4600.89578501164;5573.024720673947;5754.7122643866805;5760.423957604206;5878.147668587945;5151.5272669118285;5841.094630332305;5851.765929362778;5867.295924489089;5895.496648354642;4852.708837469622;5324.361076670824;5381.650192737459;5419.403865574863;5869.738006328489;4614.884627884329;5170.327857378667;5857.94142058591;5873.826684126901;5888.3228428729535;5229.764660590358;5284.973575132253;5711.428857114255;5740.282464691913;5745.655260684205 09:58:13 INFO - PROCESS | 1637 | |5;yui.html;748.5471289869632;772.1043589436255;838.6645341863253;843.962021709023;885.9943963601311;16255.593610159753;17240.91379543102;17867.106644667765;18386.724199137025;18797.718034229496;16550.337986480525;16677.749833752503;16767.748483772753;16770.41303554376;16815.495535133934;741.98145046374;766.1227894061296;871.6905498548019;886.2334081019936;887.6848718704864;886.8093359927615;898.7483504618684;913.1051569461931;913.8811954445946;914.6478605736806;973.0804389851593;974.6686126716914;976.3409698453551;976.623999760094;977.4233202410576;969.4328817641688;970.2626004236761;970.6505657963128;970.9853203402391;972.9708108756713;715.3025799845161;715.667214745146;716.2622498826217;716.358858821352;718.224317736843;16183.919080404641;16205.027698338137;16222.188890555424;16222.675546489005;16250.674986500322;14805.703885922221;14806.185659788707;14909.329080191308;14973.951823372368;15030.924845375703;11807.380612153176;12082;12435.005199584011;12445.564405245774;12463.75072498554;3912.197999410114;3998.280309544281;4008.83964641414;4021.879343619696;4070.430139780433;124200;126015;129965.35017324948;130214.99999999905;130275.34862325722;11441.713957151138;11498.655040348804;11745.4714537846;11783.410829458493;11889.583864564698;23477.530449390917;23628.054877804694;23646.7635323648;23663.645045324334;23666.64500032501;8237.217464340878;8257.463264887763;8310.875336869953;8313.792155196168;8468.026176989724;4531.660125490602;4612.285095810154;4625.167469855458;4842.104210721028;4888.584470319992;3493.2314890724;3652.251288485854;3891.8443262269193;3893.7469064510715;3896.1428485733095;597.3459062326746;673.515069150211;684.4763755726873;686.4131167851515;700.870338987289;655.7279028167422;661.599732162035;688.5902887781762;694.8957656351588;727.6434547071897;9445.244380449547;10147.746306342253;10185.032421919906;10300.793984120275;10393.740156496147;606.9878602427926;607.5777334752305;613.7575657615217;681.6251061915926;699.618707804248;4592.908141837144;4862.3922009748785;4863.148948933923;4868.367112275381;4868.829590964299;104271;106569;129334.35332823463;131895;172620;94454.52772736092;114791;115117.4244128774;144047.2797636005;145713;107776;107808.46095769628;113088.43455782668;116292.92974746012;118543;9842.261830362608;10286.382817031003;10563.049325560736;10690.518926648165;10734.570617175226;46669;54245;57452.425475744705;61970.38029619646;62655.6867215661;82965.58517207376;86419.56790216008;88470;88747;95269.04730952602;11946;14325.140491570539;14343.211123387897;14442.855571444152;14590;15306.234688265766;15651.530454086396;15680.764788527953;15865.365385384917;16015.599610009844;14267.500637477639;14431.35058922372;14965.251737412867;15151.848481515044;15674.373025078869;13853.238071906142;14337.696893879365;14399.92800035993;14637.024001759626;15074.54776356711;14055;14203.644908876948;14329.355179017179;14606.488772892897;14769.966102372731;11469.885301146882;11677.76644467089;11741.178117532032;12006.09954253417;12224.69438264015;11839.58561450345;11951.701207469883;12075.679188223308;12115.878841211475;12156.635300940987 09:58:13 INFO - PROCESS | 1637 | __end_tp_report 09:58:13 INFO - PROCESS | 1637 | __start_cc_report 09:58:13 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1471 09:58:13 INFO - PROCESS | 1637 | __end_cc_report 09:58:13 INFO - PROCESS | 1637 | __startTimestamp1479837493967__endTimestamp 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | ------- Summary: start ------- 09:58:13 INFO - PROCESS | 1637 | Number of tests: 6 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5834.94 Median:6142.08 stddev:662.86 (10.8%) stddev-sans-first:639.00 09:58:13 INFO - PROCESS | 1637 | Values: 3496.5 3498.4 3533.0 3539.7 3579.7 5218.2 5553.8 5831.6 5903.1 5933.9 4775.4 5448.5 5857.9 5916.9 5924.9 5268.1 5284.9 5304.6 5342.8 5355.2 5228.2 5243.4 5244.5 5398.2 5403.9 5304.2 5304.5 5314.1 5328.8 5335.2 5342.9 5346.6 5404.9 5405.9 5413.0 5194.3 5260.6 5310.0 5337.1 5359.8 5777.2 5823.7 5825.4 5831.2 5873.3 6280.1 6327.6 6337.5 6351.2 6352.9 5205.4 6286.5 6306.7 6310.9 6345.2 5830.8 5873.9 5889.8 5937.6 5950.6 6338.3 6396.4 6406.7 6418.7 6427.1 6290.4 6294.8 6311.5 6318.1 6319.3 5180.7 6240.7 6264.3 6312.0 6318.1 6367.5 6380.7 6382.5 6383.7 6407.8 6297.6 6352.3 6358.0 6373.4 6388.4 4989.3 5032.3 5045.0 5048.2 5053.3 3875.2 4029.8 4540.4 4580.6 4634.0 5947.5 6015.2 6020.8 6024.3 6027.5 6249.1 6272.0 6281.7 6294.3 6296.0 5543.2 5811.1 5933.4 5988.5 6077.1 5494.6 5533.5 5537.4 5545.8 5559.1 6489.5 6495.0 6498.4 6505.4 6509.8 5289.7 6253.5 6275.5 6282.0 6315.9 6356.2 6389.8 6398.0 6411.4 6500.3 6282.8 6289.3 6316.9 6324.0 6327.1 5295.3 6251.3 6293.1 6302.8 6306.9 6083.7 6142.1 6157.2 6158.1 6209.5 6312.8 6336.8 6342.6 6346.9 6347.5 6142.9 6184.2 6185.7 6193.5 6196.6 6114.6 6164.0 6201.9 6311.8 6323.0 6075.5 6153.0 6179.6 6179.9 6187.9 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:76075.89 Median:20004.92 stddev:173239.77 (866.0%) stddev-sans-first:173632.91 09:58:13 INFO - PROCESS | 1637 | Values: 535.7 545.6 551.1 557.4 564.8 32541.2 43245.9 43999.8 44548.8 46428.0 45695.0 45847.0 45888.0 45903.3 46180.8 10019.0 10150.7 10203.8 10216.2 10223.7 9405.6 9623.4 9629.8 9712.2 9719.4 12391.6 12642.1 12652.1 12753.4 12787.7 12565.7 12653.2 12662.7 12666.7 12675.5 12383.5 12498.3 12501.8 12506.1 12518.8 42965.0 43028.6 43090.4 43212.8 43270.6 3445.1 3631.4 3718.1 4054.5 4153.5 30898.5 31445.4 31863.8 33467.7 33819.0 3428.2 3476.6 3536.6 3555.0 3595.0 527126.0 531185.0 534174.0 534589.0 534736.0 35583.0 36442.8 36508.9 37371.3 67053.0 37911.8 38036.0 38094.7 38099.6 38202.4 15951.9 16117.0 16153.2 16168.7 16172.7 13483.3 13563.7 13587.3 13599.9 13672.5 7633.8 7745.1 7780.0 8057.6 8202.8 3147.9 3413.9 3422.7 3432.8 3451.0 986.5 988.1 1040.0 1073.9 1140.9 31728.0 33608.5 34411.1 34749.0 34890.0 1051.4 1133.9 1165.2 1166.2 1173.7 13486.5 16485.8 16541.7 16596.5 17116.1 727439.0 736083.0 737099.0 738752.0 738883.0 63491.0 63684.7 63694.4 63881.0 63944.0 1456.1 1457.7 1493.1 1502.3 1509.2 24085.6 31018.9 31232.7 31715.5 31838.5 55543.2 56269.7 56271.7 56409.4 56688.4 648750.0 653091.0 653930.0 654934.0 655804.0 24979.6 27025.9 28337.6 28491.6 28910.0 49254.8 49459.5 50294.5 50321.5 50526.0 40530.0 41512.6 42147.0 42497.0 43603.3 41099.8 41259.8 41279.7 42358.4 42689.4 40068.6 40713.4 41255.0 41313.0 41444.6 31432.2 36018.9 36121.6 36121.6 36378.5 21270.4 26971.3 26989.3 26999.5 27176.8 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:35980.17 Median:22680.76 stddev:105537.52 (465.3%) stddev-sans-first:105799.72 09:58:13 INFO - PROCESS | 1637 | Values: 377.8 381.5 390.0 394.5 413.4 19734.7 21945.8 22667.2 22694.3 22926.9 18092.6 20745.2 21419.1 21640.4 22494.0 5264.7 5554.8 5614.2 5626.1 5632.6 4116.6 4205.6 4206.3 5497.2 5606.4 5926.8 6184.6 6222.7 6246.1 6253.3 6241.4 6455.0 6908.7 6968.8 6970.8 5872.9 6015.8 6152.5 6152.8 6161.8 13291.4 13372.2 13497.7 13502.0 13513.8 2123.7 2305.5 2327.6 2404.0 2799.8 10784.9 12252.3 14062.9 15667.5 16251.8 1695.3 1954.0 2012.5 2041.3 2057.8 119400.0 121240.0 125320.0 127571.0 129728.0 9382.7 11777.8 11796.0 11935.1 12056.5 15168.5 15188.0 15205.0 15208.5 15233.0 8776.2 9002.7 9005.2 9138.8 9161.3 8955.8 8962.5 8966.4 9049.0 9128.3 4664.3 4668.4 4680.2 4683.4 4740.9 1731.4 1843.6 1852.3 1928.9 1982.1 681.9 843.5 892.7 931.4 1037.5 20510.5 22068.9 22206.3 22223.9 22275.3 962.6 981.3 1012.3 1019.8 1071.2 9299.9 11429.4 11637.0 11802.7 11847.3 618894.0 622692.0 632351.0 647980.0 650090.0 81972.0 84224.0 125222.0 132618.7 133827.0 992.3 1024.0 1034.8 1037.2 1040.1 15347.5 16019.4 18450.2 20001.4 20056.1 55154.0 55396.7 56728.4 56832.0 56943.0 82306.4 83151.0 85299.0 86488.0 87751.0 7282.6 7497.1 7594.0 8031.8 8491.8 9089.8 12520.9 13381.6 13820.5 13960.4 11510.5 11658.8 11951.3 12027.7 12133.3 10815.1 11402.4 11484.8 12005.9 12030.6 11366.9 11592.2 11663.4 11691.8 12033.3 7412.5 7593.6 7614.2 7628.1 7831.9 9764.5 11203.7 11575.4 11830.6 11854.5 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6005.11 Median:459.50 stddev:3419.40 (744.2%) stddev-sans-first:3411.83 09:58:13 INFO - PROCESS | 1637 | Values: 1446.6 1572.1 1767.6 1927.5 1929.8 8844.6 9373.8 9396.4 9409.6 9412.0 8353.4 8607.4 8625.7 8901.2 9213.5 2057.9 2440.1 2495.0 2497.2 2507.3 2459.9 2475.5 2488.0 2492.9 2494.7 2472.8 2477.1 2485.9 2488.1 2501.5 2440.8 2455.8 2467.9 2468.4 2470.6 2076.6 2464.1 2468.2 2470.3 2473.9 9246.1 9257.4 9338.6 9367.7 9371.1 5327.6 5391.4 5501.2 5501.5 5506.4 3664.9 3731.5 3820.8 3829.1 3849.2 1097.3 1124.2 1126.8 1146.9 1148.3 12276.8 12358.3 12359.5 12363.8 12389.8 4831.9 4833.7 4896.4 5010.7 5030.4 10500.5 10501.9 10507.2 10513.9 10521.7 5576.5 5594.3 5605.7 5608.7 5612.0 4527.7 4836.2 5075.6 5130.1 5135.8 4092.8 4095.4 4182.1 4200.7 4222.2 1128.9 1297.6 1309.4 1309.5 1324.0 922.6 949.0 952.0 957.4 1005.6 5856.4 6051.1 6081.6 6145.1 6254.7 444.5 446.4 455.2 457.6 461.3 4081.8 4129.3 4131.5 4147.4 4223.8 11135.0 11511.4 11535.9 11576.9 11589.2 11409.8 11432.2 11440.3 11450.4 11454.3 8222.6 8553.5 8589.8 8605.0 8776.0 6026.2 6030.0 6119.3 6260.0 6284.9 10298.3 10477.3 10712.6 10730.3 10745.4 10625.5 10627.7 10764.3 10816.8 10923.0 7299.2 7503.5 7700.0 7931.0 8083.1 8735.3 8884.4 8919.2 8920.2 8931.5 7347.4 7453.5 7556.7 7576.8 8097.2 7897.6 7927.7 8071.0 8103.2 8150.2 7086.6 7212.9 7218.3 7222.3 7226.8 5241.2 5316.0 5357.1 5378.0 5506.3 5298.4 5408.5 5440.4 5516.5 5542.8 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4631.99 Median:5160.93 stddev:1410.95 (27.3%) stddev-sans-first:1376.93 09:58:13 INFO - PROCESS | 1637 | Values: 299.9 304.3 308.7 313.9 317.9 5259.0 5544.2 5566.2 5611.8 5621.7 4597.5 4760.0 4775.7 5258.2 5529.6 3569.1 4169.6 4184.2 4194.2 4231.3 3682.4 3844.1 4141.3 4172.5 4243.0 3255.6 3968.7 3977.2 4162.9 4188.7 4013.0 4093.3 4162.3 4190.9 4194.3 3609.8 3630.7 3634.3 3643.6 3646.0 4447.8 5117.7 5350.7 5383.6 5389.5 4326.7 4388.5 4769.7 4847.8 4881.6 5473.5 5522.6 5743.7 5812.3 5831.4 3867.2 3876.4 4044.3 4048.7 4052.3 5056.9 5678.7 5958.4 5974.4 5990.5 3304.3 4055.3 4055.9 4089.9 4199.2 3625.3 3831.7 3906.4 4198.3 4213.8 4413.6 4713.7 4740.5 4749.7 4802.4 4801.5 4822.5 5743.4 5764.6 5782.6 1391.5 1418.5 1423.6 1430.5 1430.6 872.8 881.6 916.5 941.4 963.0 4706.9 4807.9 4973.6 5367.1 5368.4 5797.7 5830.4 5916.8 5971.8 5984.4 4704.8 4705.6 4925.5 5380.4 5409.8 3034.0 3056.6 3060.3 3062.3 3065.6 4590.4 5246.9 5903.3 6062.5 6070.2 4924.2 5424.3 5583.7 5936.9 5938.3 5476.1 5641.0 5985.7 6048.9 6075.5 5658.9 5835.4 5842.8 5843.3 5947.0 5569.8 5821.0 5840.6 5843.2 5851.9 5776.0 5782.7 5974.5 6095.0 6175.4 3390.4 3733.8 4049.0 4066.3 4074.6 5098.9 5644.5 5728.1 5854.3 5891.8 4600.9 5573.0 5754.7 5760.4 5878.1 5151.5 5841.1 5851.8 5867.3 5895.5 4852.7 5324.4 5381.7 5419.4 5869.7 4614.9 5170.3 5857.9 5873.8 5888.3 5229.8 5285.0 5711.4 5740.3 5745.7 09:58:13 INFO - PROCESS | 1637 | 09:58:13 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:25033.84 Median:18592.22 stddev:38835.83 (208.9%) stddev-sans-first:38901.96 09:58:13 INFO - PROCESS | 1637 | Values: 748.5 772.1 838.7 844.0 886.0 16255.6 17240.9 17867.1 18386.7 18797.7 16550.3 16677.7 16767.7 16770.4 16815.5 742.0 766.1 871.7 886.2 887.7 886.8 898.7 913.1 913.9 914.6 973.1 974.7 976.3 976.6 977.4 969.4 970.3 970.7 971.0 973.0 715.3 715.7 716.3 716.4 718.2 16183.9 16205.0 16222.2 16222.7 16250.7 14805.7 14806.2 14909.3 14974.0 15030.9 11807.4 12082.0 12435.0 12445.6 12463.8 3912.2 3998.3 4008.8 4021.9 4070.4 124200.0 126015.0 129965.4 130215.0 130275.3 11441.7 11498.7 11745.5 11783.4 11889.6 23477.5 23628.1 23646.8 23663.6 23666.6 8237.2 8257.5 8310.9 8313.8 8468.0 4531.7 4612.3 4625.2 4842.1 4888.6 3493.2 3652.3 3891.8 3893.7 3896.1 597.3 673.5 684.5 686.4 700.9 655.7 661.6 688.6 694.9 727.6 9445.2 10147.7 10185.0 10300.8 10393.7 607.0 607.6 613.8 681.6 699.6 4592.9 4862.4 4863.1 4868.4 4868.8 104271.0 106569.0 129334.4 131895.0 172620.0 94454.5 114791.0 115117.4 144047.3 145713.0 107776.0 107808.5 113088.4 116292.9 118543.0 9842.3 10286.4 10563.0 10690.5 10734.6 46669.0 54245.0 57452.4 61970.4 62655.7 82965.6 86419.6 88470.0 88747.0 95269.0 11946.0 14325.1 14343.2 14442.9 14590.0 15306.2 15651.5 15680.8 15865.4 16015.6 14267.5 14431.4 14965.3 15151.8 15674.4 13853.2 14337.7 14399.9 14637.0 15074.5 14055.0 14203.6 14329.4 14606.5 14770.0 11469.9 11677.8 11741.2 12006.1 12224.7 11839.6 11951.7 12075.7 12115.9 12156.6 09:58:13 INFO - PROCESS | 1637 | -------- Summary: end -------- 09:58:13 INFO - PROCESS | 1637 | 09:58:14 INFO - TEST-INFO | 1637: exit 0 09:58:14 INFO - TEST-OK | dromaeo_css | took 1115756ms 09:58:14 INFO - TEST-START | kraken 09:58:14 INFO - operating with platform_type : mac_ 09:58:14 INFO - Initialising browser for kraken test... 09:58:14 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpoQso1w/profile http://localhost:49240/getInfo.html) 09:58:16 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:58:16 INFO - PROCESS | 1646 | colorDepth:24 09:58:16 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:58:16 INFO - PROCESS | 1646 | __metrics 09:58:16 INFO - PROCESS | 1646 | 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" 09:58:16 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:17 INFO - TEST-INFO | 1646: exit 0 09:58:17 INFO - Browser initialized. 09:58:17 INFO - Running cycle 1/1 for kraken test... 09:58:17 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpoQso1w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:29 INFO - PROCESS | 1648 | RSS: Main: 168394752 09:58:29 INFO - PROCESS | 1648 | 09:58:32 INFO - PROCESS | 1648 | 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) 09:58:33 INFO - PROCESS | 1648 | RSS: Main: 218615808 09:58:33 INFO - PROCESS | 1648 | 09:58:35 INFO - PROCESS | 1648 | 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) 09:58:36 INFO - PROCESS | 1648 | RSS: Main: 228904960 09:58:36 INFO - PROCESS | 1648 | 09:58:39 INFO - PROCESS | 1648 | 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) 09:58:40 INFO - PROCESS | 1648 | RSS: Main: 325070848 09:58:40 INFO - PROCESS | 1648 | 09:58:42 INFO - PROCESS | 1648 | 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) 09:58:42 INFO - PROCESS | 1648 | RSS: Main: 228495360 09:58:42 INFO - PROCESS | 1648 | 09:58:44 INFO - PROCESS | 1648 | 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) 09:58:45 INFO - PROCESS | 1648 | RSS: Main: 229203968 09:58:45 INFO - PROCESS | 1648 | 09:58:48 INFO - PROCESS | 1648 | 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) 09:58:48 INFO - PROCESS | 1648 | RSS: Main: 267202560 09:58:48 INFO - PROCESS | 1648 | 09:58:51 INFO - PROCESS | 1648 | 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) 09:58:52 INFO - PROCESS | 1648 | RSS: Main: 266526720 09:58:52 INFO - PROCESS | 1648 | 09:58:54 INFO - PROCESS | 1648 | 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) 09:58:55 INFO - PROCESS | 1648 | RSS: Main: 264417280 09:58:55 INFO - PROCESS | 1648 | 09:58:56 INFO - PROCESS | 1648 | 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) 09:58:57 INFO - PROCESS | 1648 | RSS: Main: 241713152 09:58:57 INFO - PROCESS | 1648 | 09:58:59 INFO - PROCESS | 1648 | 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) 09:59:00 INFO - PROCESS | 1648 | RSS: Main: 244142080 09:59:00 INFO - PROCESS | 1648 | 09:59:02 INFO - PROCESS | 1648 | 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) 09:59:02 INFO - PROCESS | 1648 | RSS: Main: 249257984 09:59:02 INFO - PROCESS | 1648 | 09:59:05 INFO - PROCESS | 1648 | 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) 09:59:06 INFO - PROCESS | 1648 | RSS: Main: 264556544 09:59:06 INFO - PROCESS | 1648 | 09:59:09 INFO - PROCESS | 1648 | 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) 09:59:09 INFO - PROCESS | 1648 | RSS: Main: 258859008 09:59:09 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | 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) 09:59:11 INFO - PROCESS | 1648 | RSS: Main: 302551040 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | __start_tp_report 09:59:11 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 09:59:11 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 09:59:11 INFO - PROCESS | 1648 | |i|pagename|runs| 09:59:11 INFO - PROCESS | 1648 | |0;ai-astar;114;113;113;113;113;113;112;113;116;161 09:59:11 INFO - PROCESS | 1648 | |1;audio-beat-detection;140;107;109;139;104;106;107;107;136;106 09:59:11 INFO - PROCESS | 1648 | |2;audio-dft;184;182;183;181;184;181;181;183;177;183 09:59:11 INFO - PROCESS | 1648 | |3;audio-fft;81;59;57;86;60;59;58;58;82;57 09:59:11 INFO - PROCESS | 1648 | |4;audio-oscillator;88;70;93;69;66;69;92;67;68;89 09:59:11 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;86;90;87;89;86;89;87;89;85 09:59:11 INFO - PROCESS | 1648 | |6;imaging-darkroom;104;150;150;94;94;93;94;94;94;92 09:59:11 INFO - PROCESS | 1648 | |7;imaging-desaturate;85;87;84;85;84;84;89;85;86;84 09:59:11 INFO - PROCESS | 1648 | |8;json-parse-financial;53;52;52;52;54;55;55;54;54;55 09:59:11 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;64;69;69;79;74;70;68;68;68;69 09:59:11 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;81;82;84;86;89;92;85;80;81;89 09:59:11 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;148;146;151;137;135;135;140;137;165;131 09:59:11 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;161;164;157;159;161;169;160;157;159;161 09:59:11 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;63;66;67;68;75;84;82;84;75;65 09:59:11 INFO - PROCESS | 1648 | __end_tp_report 09:59:11 INFO - PROCESS | 1648 | __start_cc_report 09:59:11 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1956 09:59:11 INFO - PROCESS | 1648 | __end_cc_report 09:59:11 INFO - PROCESS | 1648 | __startTimestamp1479837551894__endTimestamp 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | ------- Summary: start ------- 09:59:11 INFO - PROCESS | 1648 | Number of tests: 14 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:118.10 Median:113.00 stddev:15.11 (13.4%) stddev-sans-first:15.95 09:59:11 INFO - PROCESS | 1648 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 116.0 161.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:116.10 Median:108.00 stddev:15.42 (14.3%) stddev-sans-first:13.72 09:59:11 INFO - PROCESS | 1648 | Values: 140.0 107.0 109.0 139.0 104.0 106.0 107.0 107.0 136.0 106.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:181.90 Median:183.00 stddev:2.08 (1.1%) stddev-sans-first:2.06 09:59:11 INFO - PROCESS | 1648 | Values: 184.0 182.0 183.0 181.0 184.0 181.0 181.0 183.0 177.0 183.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:65.70 Median:59.50 stddev:12.04 (20.2%) stddev-sans-first:11.42 09:59:11 INFO - PROCESS | 1648 | Values: 81.0 59.0 57.0 86.0 60.0 59.0 58.0 58.0 82.0 57.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:79.00 stddev:11.67 (14.8%) stddev-sans-first:11.69 09:59:11 INFO - PROCESS | 1648 | Values: 88.0 70.0 93.0 69.0 66.0 69.0 92.0 67.0 68.0 89.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.81 (2.0%) stddev-sans-first:1.74 09:59:11 INFO - PROCESS | 1648 | Values: 90.0 86.0 90.0 87.0 89.0 86.0 89.0 87.0 89.0 85.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:94.00 stddev:23.48 (25.0%) stddev-sans-first:24.89 09:59:11 INFO - PROCESS | 1648 | Values: 104.0 150.0 150.0 94.0 94.0 93.0 94.0 94.0 94.0 92.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:1.64 (1.9%) stddev-sans-first:1.73 09:59:11 INFO - PROCESS | 1648 | Values: 85.0 87.0 84.0 85.0 84.0 84.0 89.0 85.0 86.0 84.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.26 (2.3%) stddev-sans-first:1.32 09:59:11 INFO - PROCESS | 1648 | Values: 53.0 52.0 52.0 52.0 54.0 55.0 55.0 54.0 54.0 55.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:69.00 stddev:4.05 (5.9%) stddev-sans-first:3.71 09:59:11 INFO - PROCESS | 1648 | Values: 64.0 69.0 69.0 79.0 74.0 70.0 68.0 68.0 68.0 69.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.50 stddev:4.07 (4.8%) stddev-sans-first:4.06 09:59:11 INFO - PROCESS | 1648 | Values: 81.0 82.0 84.0 86.0 89.0 92.0 85.0 80.0 81.0 89.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:143.00 stddev:10.18 (7.1%) stddev-sans-first:10.60 09:59:11 INFO - PROCESS | 1648 | Values: 148.0 146.0 151.0 137.0 135.0 135.0 140.0 137.0 165.0 131.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:161.00 stddev:3.55 (2.2%) stddev-sans-first:3.77 09:59:11 INFO - PROCESS | 1648 | Values: 161.0 164.0 157.0 159.0 161.0 169.0 160.0 157.0 159.0 161.0 09:59:11 INFO - PROCESS | 1648 | 09:59:11 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:75.00 stddev:8.20 (10.9%) stddev-sans-first:7.87 09:59:11 INFO - PROCESS | 1648 | Values: 63.0 66.0 67.0 68.0 75.0 84.0 82.0 84.0 75.0 65.0 09:59:11 INFO - PROCESS | 1648 | -------- Summary: end -------- 09:59:11 INFO - PROCESS | 1648 | 09:59:12 INFO - TEST-INFO | 1648: exit 0 09:59:12 INFO - TEST-OK | kraken | took 58099ms 09:59:12 INFO - SUITE-END | took 1173s 09:59:13 INFO - Completed test suite (00:19:34) 09:59:13 INFO - javascript benchmark 09:59:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3496.510488531606, 3498.3702933471977, 3533.0, 3539.681428671419, 3579.67782899539, 5218.21726740989, 5553.750081246354, 5831.591788574801, 5903.055511118217, 5933.940660593393, 4775.426948766144, 5448.536874365674, 5857.912131318034, 5916.911246331309, 5924.940750592493, 5268.104422248227, 5284.920726189111, 5304.575633949294, 5342.839714808562, 5355.169948657965, 5228.215767634847, 5243.449437809032, 5244.475552444763, 5398.217258497515, 5403.891922161574, 5304.151335786275, 5304.469553044703, 5314.096603577382, 5328.760205790749, 5335.1730481775385, 5342.866428339303, 5346.599005074635, 5404.864878378033, 5405.918911216335, 5412.972935135338, 5194.324737784102, 5260.631755777097, 5310.017646735367, 5337.11937530307, 5359.758810853521, 5777.220075289824, 5823.6505809651135, 5825.446582574648, 5831.183634291203, 5873.2952045754355, 6280.057991301342, 6327.5570710050315, 6337.524685648548, 6351.2060992375955, 6352.872942541169, 5205.417945072222, 6286.528510361695, 6306.653134077624, 6310.936890631081, 6345.206849143857, 5830.7667693292215, 5873.911891321633, 5889.793857214977, 5937.554683398763, 5950.583459157818, 6338.291725091162, 6396.360363963567, 6406.743730250831, 6418.679066046679, 6427.068075129174, 6290.370962903673, 6294.779682711083, 6311.526635502356, 6318.052292156121, 6319.273283572448, 5180.663256888291, 6240.687965601702, 6264.342244064399, 6312.0, 6318.147050148171, 6367.4906007519285, 6380.7128679209545, 6382.521310901701, 6383.71273292703, 6407.807765767035, 6297.559170857996, 6352.301246862841, 6358.0, 6373.362663733589, 6388.361163883575, 4989.3264409305, 5032.270320803536, 5044.974775126174, 5048.167052436307, 5053.292539044537, 3875.205582855508, 4029.81865816039, 4540.432445944257, 4580.587747102777, 4634.0, 5947.464728174485, 6015.157877897098, 6020.819375418744, 6024.337322894478, 6027.48766354856, 6249.0626406039455, 6272.0, 6281.7173227204885, 6294.307626161119, 6295.96852015737, 5543.168524721244, 5811.128330750336, 5933.436323549256, 5988.520918326523, 6077.088436734525, 5494.560435165177, 5533.501984821385, 5537.446255374431, 5545.77816887328, 5559.054960656698, 6489.513286503437, 6494.967525162344, 6498.350164983463, 6505.41451269388, 6509.804705858832, 5289.7355132244, 6253.499720022478, 6275.497960163085, 6281.9948808190475, 6315.873682526416, 6356.2054743157105, 6389.7763578274535, 6398.0402939559435, 6411.4229719325485, 6500.3174666659315, 6282.811515654537, 6289.276733175743, 6316.936830631635, 6323.9683801580695, 6327.114204011534, 5295.3116094908955, 6251.3123556407845, 6293.087502312231, 6302.779402720883, 6306.873862522815, 6083.726232319467, 6142.078688196806, 6157.199564056644, 6158.137860699416, 6209.472194863487, 6312.842178945563, 6336.778212762531, 6342.619442833445, 6346.936530634819, 6347.460465860454, 6142.877142457215, 6184.226971628546, 6185.659788711664, 6193.504519638328, 6196.566240363131, 6114.571104522845, 6164.044573091178, 6201.875962480635, 6311.810645680637, 6323.019931910561, 6075.513958883367, 6153.046277826944, 6179.567430279837, 6179.907301390573, 6187.876242475035], "value": 5794.242466001175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.7000079955225, 545.5962587685111, 551.1099574187683, 557.3952261795952, 564.7966731976489, 32541.186470338253, 43245.91885202871, 43999.78000110003, 44548.77725611372, 46428.0, 45695.0, 45847.0, 45888.0, 45903.31145032827, 46180.76909615456, 10018.998100189996, 10150.74623134424, 10203.795924081514, 10216.233782466345, 10223.744406389826, 9405.576749046275, 9623.422594644344, 9629.759256018586, 9712.223022158245, 9719.416834989923, 12391.566295179671, 12642.115051946368, 12652.114351995364, 12753.426095825664, 12787.680307992328, 12565.685857853536, 12653.240805551697, 12662.683432914158, 12666.683332916704, 12675.492980280778, 12383.504659813596, 12498.25010499373, 12501.812472812915, 12506.062045346636, 12518.81221781674, 42965.0, 43028.56971430277, 43090.35364469504, 43212.783936080436, 43270.56729432697, 3445.0870519312402, 3631.382664946965, 3718.1448266898765, 4054.513458384983, 4153.460050193456, 30898.53652195219, 31445.37109257825, 31863.84068079668, 33467.6653233467, 33819.0, 3428.211511352403, 3476.6001909780193, 3536.5756109266795, 3554.964450355489, 3595.0473124621994, 527126.0, 531185.0, 534174.0, 534589.0, 534736.0, 35583.0, 36442.8177859109, 36508.90473285806, 37371.25257494835, 67053.0, 37911.81044094762, 38036.0, 38094.666686665834, 38099.61900381016, 38202.42696359557, 15951.920240398724, 16116.952398094087, 16153.192340382933, 16168.67662646764, 16172.676546469005, 13483.325833708275, 13563.728725425437, 13587.252701101534, 13599.864001360058, 13672.52146174879, 7633.847323053508, 7745.148033716287, 7780.02749656293, 8057.637406316612, 8202.753917382488, 3147.905562833119, 3413.8805141819917, 3422.6748458896363, 3432.811195384228, 3451.0, 986.5215370545279, 988.1255089245925, 1039.9964034706393, 1073.8603981482356, 1140.8591408591408, 31728.04815855528, 33608.49587256193, 34411.13972150716, 34749.0, 34890.0, 1051.3954476176077, 1133.8525991621202, 1165.2309475746135, 1166.2128063557066, 1173.7065733566608, 13486.527971521145, 16485.83514164867, 16541.6691666166, 16596.548381616645, 17116.14419279031, 727439.0, 736083.0, 737099.0, 738752.0, 738883.0, 63491.0, 63684.68157659182, 63694.36305636884, 63881.0, 63944.0, 1456.097219723778, 1457.7084583083426, 1493.0519120358433, 1502.3389708527993, 1509.2453773113443, 24085.610281997535, 31018.914337998063, 31232.68767312298, 31715.524267136472, 31838.522422163223, 55543.166852498056, 56269.71865140648, 56271.71864140653, 56409.43590564042, 56688.43311566996, 648750.0, 653091.0, 653930.0, 654934.0, 655804.0, 24979.625305620066, 27025.86487067552, 28337.574936375557, 28491.572626411034, 28910.0, 49254.75372623114, 49459.50540494693, 50294.49705502898, 50321.49678503168, 50525.98948021093, 40530.0, 41512.58487415208, 42147.0, 42497.0, 43603.345949810144, 41099.7945010273, 41259.7937010313, 41279.66563510788, 42358.36462453004, 42689.359659605754, 40068.59931400649, 40713.38929915994, 41254.9686257834, 41313.0, 41444.585554144076, 31432.214194645316, 36018.857936215216, 36121.63878361183, 36121.63878361288, 36378.4543231857, 21270.36188914335, 26971.325716857235, 26989.325266868484, 26999.460010800067, 27176.845475263206], "value": 20134.040482016208, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.84319507404444, 381.4583291725749, 390.0, 394.4891365681442, 413.4212103055722, 19734.703979440303, 21945.780542194574, 22667.206647767332, 22694.31917042487, 22926.88536557317, 18092.63814723705, 20745.17019319229, 21419.14323427061, 21640.350789476302, 22494.0, 5264.657797243177, 5554.833354999357, 5614.157876318544, 5626.12795016771, 5632.577556683244, 4116.567760385161, 4205.642520385771, 4206.348016057516, 5497.202905578689, 5606.383297837255, 5926.822195334147, 6184.56708030438, 6222.688865556726, 6246.063090536433, 6253.343398943114, 6241.407066328714, 6454.967725161391, 6908.723651053977, 6968.825779355506, 6970.79087627372, 5872.911906321408, 6015.819525414245, 6152.477039451652, 6152.78465253714, 6161.784337548209, 13291.401886915111, 13372.197668139943, 13497.662558436019, 13501.98735094862, 13513.797293040612, 2123.7132987046707, 2305.4582173189287, 2327.5577640248466, 2403.951920961571, 2799.762020228283, 10784.946075269652, 12252.326122063283, 14062.859371406257, 15667.451639192574, 16251.83748162515, 1695.3303445139225, 1954.0, 2012.4767560434245, 2041.2651445479764, 2057.794220577945, 119400.0, 121240.0, 125320.0, 127571.0, 129727.99999999905, 9382.671606493877, 11777.764444711058, 11795.997340226006, 11935.104867134825, 12056.457459414347, 15168.469103581321, 15188.01277916932, 15205.0, 15208.467703630542, 15233.00985435943, 8776.210141087333, 9002.729918102468, 9005.189532942066, 9138.81722365549, 9161.312901532412, 8955.776105597412, 8962.462252264884, 8966.372353935292, 9048.954755226181, 9128.26973842091, 4664.346991421204, 4668.393108895822, 4680.227762419231, 4683.437987441461, 4740.905181896343, 1731.37670438642, 1843.5667618109733, 1852.2961274715522, 1928.9421317360502, 1982.0971867007545, 681.9454443644496, 843.4517563583597, 892.6875593542338, 931.4364809290417, 1037.5175543372368, 20510.487237818874, 22068.88965555162, 22206.333809985725, 22223.888805559658, 22275.3317400475, 962.6005207838668, 981.2640519610293, 1012.3015119567475, 1019.7501612104934, 1071.2340676416243, 9299.860502092473, 11429.428528573539, 11636.976600371192, 11802.704932376588, 11847.348395838138, 618894.0, 622692.0, 632351.0, 647980.0, 650090.0, 81972.0, 84224.0, 125222.0, 132618.67381326063, 133827.0, 992.2558081438921, 1024.0, 1034.7516596017051, 1037.159042699215, 1040.0743338428804, 15347.539573812803, 16019.439319623758, 18450.169742361893, 20001.399958001282, 20056.097475613926, 55153.99999999839, 55396.72301638627, 56728.432715672316, 56831.98178404466, 56943.0, 82306.3538729234, 83151.0, 85299.0, 86488.0, 87751.0, 7282.563046217244, 7497.10034795828, 7593.962030189814, 8031.799205019921, 8491.830163396575, 9089.818323617887, 12520.874791252336, 13381.598552043455, 13820.516281930084, 13960.441582336998, 11510.48202830858, 11658.76682466363, 11951.342676152548, 12027.699307517381, 12133.332666703063, 10815.080718139046, 11402.429878506207, 11484.82772758426, 12005.939970300093, 12030.63908082759, 11366.920142586441, 11592.188546801643, 11663.358515281401, 11691.766164676828, 12033.338166400932, 7412.518186317872, 7593.620318983918, 7614.238576142341, 7628.084629844232, 7831.921680783119, 9764.511774411392, 11203.655561332693, 11575.363355015219, 11830.645080647595, 11854.466549005489], "value": 9657.992523450996, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.6021843992903, 1572.1117568573754, 1767.5846176148602, 1927.5084853362407, 1929.7780755213146, 8844.601992910319, 9373.81252374952, 9396.436213827157, 9409.576569054392, 9411.952940235298, 8353.415260931737, 8607.354448416363, 8625.698100566482, 8901.243394311476, 9213.493257870814, 2057.917683292674, 2440.1215562397524, 2495.026939493599, 2497.2258599834017, 2507.3104896153613, 2459.9262022139364, 2475.5420247254224, 2487.9751202487923, 2492.850428974258, 2494.650748895156, 2472.8269021168526, 2477.1082410332265, 2485.875706214691, 2488.1167185649106, 2501.5122051200024, 2440.7681270279295, 2455.8280920335583, 2467.8519288842726, 2468.358226861029, 2470.6047032474717, 2076.5535409886797, 2464.1006032798077, 2468.173551571842, 2470.258922323296, 2473.888675009611, 9246.121618446237, 9257.398269112486, 9338.626454941794, 9367.718968430958, 9371.109744574254, 5327.600429967729, 5391.3799913009725, 5501.202325662796, 5501.477359650867, 5506.421825708283, 3664.9450258246147, 3731.5149030626117, 3820.751651142667, 3829.0618798394294, 3849.2301539692176, 1097.2703152403642, 1124.1624989382963, 1126.8140756775122, 1146.868110167333, 1148.311013391958, 12276.75446491074, 12358.320292383914, 12359.505619775287, 12363.814542781865, 12389.752204955941, 4831.903361932742, 4833.661643684908, 4896.436909755424, 5010.69935803853, 5030.4215015272985, 10500.474976251156, 10501.9474902625, 10507.211959102944, 10513.9474302628, 10521.684349469375, 5576.525995290364, 5594.300712410949, 5605.747741351649, 5608.663480191201, 5612.045952188057, 4527.705699129546, 4836.153673107192, 5075.56857667095, 5130.05094057593, 5135.794568217304, 4092.7953602319767, 4095.365218391154, 4182.142660754538, 4200.7479551227025, 4222.218889505451, 1128.8758236593967, 1297.5912587534897, 1309.4369421148997, 1309.5482058689736, 1324.0466863858007, 922.5802259971697, 948.9762755931157, 951.9904800951902, 957.3633533700012, 1005.5776573839005, 5856.443637854397, 6051.122587224828, 6081.604695694766, 6145.078238264206, 6254.718537665816, 444.4999375702335, 446.41296694846176, 455.2033940603943, 457.6498978281617, 461.3448902558375, 4081.8163182656854, 4129.339305711072, 4131.5248746393945, 4147.3778933160265, 4223.788810559459, 11135.0, 11511.366874821964, 11535.942320288344, 11576.942115289537, 11589.188756787113, 11409.828852567218, 11432.199746017699, 11440.256383335056, 11450.427478626036, 11454.312741235386, 8222.62998165084, 8553.486790792573, 8589.828203435896, 8604.956975215084, 8776.034636190014, 6026.246719160105, 6029.969850150721, 6119.265688117276, 6259.96870015647, 6284.905726414016, 10298.330608510423, 10477.266591338534, 10712.625058122929, 10730.302530335503, 10745.355278683304, 10625.521851516545, 10627.681169564925, 10764.300320479144, 10816.783664326827, 10922.962318579723, 7299.160596531467, 7503.474756766974, 7699.961500192463, 7931.0, 8083.070446898682, 8735.301175905784, 8884.422512536665, 8919.241864441596, 8920.152585504367, 8931.508767017876, 7347.375473084848, 7453.515521491086, 7556.6977320908745, 7576.772696819105, 8097.2307630774985, 7897.565633890191, 7927.682892684459, 8070.991126109237, 8103.189681031849, 8150.225728555778, 7086.610236436839, 7212.927870721435, 7218.27817218295, 7222.313880181375, 7226.819329516804, 5241.161414173714, 5315.973420133029, 5357.089294819968, 5377.973110134424, 5506.311711036121, 5298.364196296469, 5408.486193811582, 5440.374356948843, 5516.531094856982, 5542.7782888685615], "value": 4722.678473288336, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.88754217168565, 304.2651995431034, 308.68822489285816, 313.8524893300148, 317.90939582219073, 5259.0, 5544.223808666785, 5566.248556444879, 5611.775528978836, 5621.718914054296, 4597.471290801559, 4759.976200119003, 4775.713457192571, 5258.211268309756, 5529.585281103923, 3569.109021692431, 4169.62473377396, 4184.205001049791, 4194.203101410722, 4231.280682284004, 3682.39240525313, 3844.0966372902344, 4141.295979683446, 4172.541020487759, 4242.97878510607, 3255.641879393265, 3968.6825053995613, 3977.164795392971, 4162.875113746592, 4188.727732697365, 4012.996750812297, 4093.3450647896493, 4162.250794856925, 4190.904547726137, 4194.30793919003, 3609.837557309901, 3630.7276954228273, 3634.345817752803, 3643.562772467321, 3646.0, 4447.777611119431, 5117.7185254810975, 5350.652207606493, 5383.596230282745, 5389.51494365509, 4326.718763280408, 4388.539203383662, 4769.689970151963, 4847.75761211938, 4881.609471242327, 5473.452654734535, 5522.646951496909, 5743.655380677173, 5812.285375814145, 5831.358550559435, 3867.245887052026, 3876.3991581304945, 4044.312466880637, 4048.6761059115493, 4052.2705912935658, 5056.949430505685, 5678.65582829136, 5958.404159584007, 5974.3726908674835, 5990.490808281258, 3304.339132173575, 4055.310597198424, 4055.9391609125887, 4089.9386509202386, 4199.202151591188, 3625.3474374612297, 3831.700979412386, 3906.43356713275, 4198.328267477189, 4213.810378532973, 4413.6248418884115, 4713.74074425903, 4740.502247263988, 4749.691270067435, 4802.447718512346, 4801.51984801524, 4822.469528351879, 5743.396943320975, 5764.596478246566, 5782.566307526952, 1391.4781956766212, 1418.4964337660188, 1423.6404233956546, 1430.5493769462587, 1430.6208854653526, 872.8079822439054, 881.6164968238836, 916.4867674102524, 941.4022095968973, 962.9807403851884, 4706.858794236178, 4807.879803004883, 4973.626977976666, 5367.1412573988755, 5368.3826359968325, 5797.739101740432, 5830.414295208417, 5916.822495325147, 5971.775785963867, 5984.371640977722, 4704.835330763407, 4705.576498115186, 4925.532074452921, 5380.381256155514, 5409.837704868782, 3033.9848300758354, 3056.587360706321, 3060.296131889633, 3062.31098002952, 3065.632124145117, 4590.449146102423, 5246.947530524722, 5903.258538731085, 6062.545309101835, 6070.2108725865355, 4924.162892308316, 5424.267723857308, 5583.720813959286, 5936.881262374728, 5938.317093534299, 5476.123820188751, 5641.0, 5985.6955072296605, 6048.939510604838, 6075.544334174866, 5658.943410565842, 5835.387284335084, 5842.766289348378, 5843.328017277898, 5947.0, 5569.799929028092, 5821.0, 5840.649561026352, 5843.179377013896, 5851.882962340815, 5776.0, 5782.681952492653, 5974.536238621566, 6095.0, 6175.44421002112, 3390.406678831145, 3733.775973441602, 4048.959510404858, 4066.268071747114, 4074.5721699221153, 5098.898022039612, 5644.5286149018875, 5728.083506639085, 5854.297484301911, 5891.820026590189, 4600.89578501164, 5573.024720673947, 5754.7122643866805, 5760.423957604206, 5878.147668587945, 5151.5272669118285, 5841.094630332305, 5851.765929362778, 5867.295924489089, 5895.496648354642, 4852.708837469622, 5324.361076670824, 5381.650192737459, 5419.403865574863, 5869.738006328489, 4614.884627884329, 5170.327857378667, 5857.94142058591, 5873.826684126901, 5888.3228428729535, 5229.764660590358, 5284.973575132253, 5711.428857114255, 5740.282464691913, 5745.655260684205], "value": 4185.060824402551, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.5471289869632, 772.1043589436255, 838.6645341863253, 843.962021709023, 885.9943963601311, 16255.593610159753, 17240.91379543102, 17867.106644667765, 18386.724199137025, 18797.718034229496, 16550.337986480525, 16677.749833752503, 16767.748483772753, 16770.41303554376, 16815.495535133934, 741.98145046374, 766.1227894061296, 871.6905498548019, 886.2334081019936, 887.6848718704864, 886.8093359927615, 898.7483504618684, 913.1051569461931, 913.8811954445946, 914.6478605736806, 973.0804389851593, 974.6686126716914, 976.3409698453551, 976.623999760094, 977.4233202410576, 969.4328817641688, 970.2626004236761, 970.6505657963128, 970.9853203402391, 972.9708108756713, 715.3025799845161, 715.667214745146, 716.2622498826217, 716.358858821352, 718.224317736843, 16183.919080404641, 16205.027698338137, 16222.188890555424, 16222.675546489005, 16250.674986500322, 14805.703885922221, 14806.185659788707, 14909.329080191308, 14973.951823372368, 15030.924845375703, 11807.380612153176, 12082.0, 12435.005199584011, 12445.564405245774, 12463.75072498554, 3912.197999410114, 3998.280309544281, 4008.83964641414, 4021.879343619696, 4070.430139780433, 124200.0, 126015.0, 129965.35017324948, 130214.99999999905, 130275.34862325722, 11441.713957151138, 11498.655040348804, 11745.4714537846, 11783.410829458493, 11889.583864564698, 23477.530449390917, 23628.054877804694, 23646.7635323648, 23663.645045324334, 23666.64500032501, 8237.217464340878, 8257.463264887763, 8310.875336869953, 8313.792155196168, 8468.026176989724, 4531.660125490602, 4612.285095810154, 4625.167469855458, 4842.104210721028, 4888.584470319992, 3493.2314890724, 3652.251288485854, 3891.8443262269193, 3893.7469064510715, 3896.1428485733095, 597.3459062326746, 673.515069150211, 684.4763755726873, 686.4131167851515, 700.870338987289, 655.7279028167422, 661.599732162035, 688.5902887781762, 694.8957656351588, 727.6434547071897, 9445.244380449547, 10147.746306342253, 10185.032421919906, 10300.793984120275, 10393.740156496147, 606.9878602427926, 607.5777334752305, 613.7575657615217, 681.6251061915926, 699.618707804248, 4592.908141837144, 4862.3922009748785, 4863.148948933923, 4868.367112275381, 4868.829590964299, 104271.0, 106569.0, 129334.35332823463, 131895.0, 172620.0, 94454.52772736092, 114791.0, 115117.4244128774, 144047.2797636005, 145713.0, 107776.0, 107808.46095769628, 113088.43455782668, 116292.92974746012, 118543.0, 9842.261830362608, 10286.382817031003, 10563.049325560736, 10690.518926648165, 10734.570617175226, 46669.0, 54245.0, 57452.425475744705, 61970.38029619646, 62655.6867215661, 82965.58517207376, 86419.56790216008, 88470.0, 88747.0, 95269.04730952602, 11946.0, 14325.140491570539, 14343.211123387897, 14442.855571444152, 14590.0, 15306.234688265766, 15651.530454086396, 15680.764788527953, 15865.365385384917, 16015.599610009844, 14267.500637477639, 14431.35058922372, 14965.251737412867, 15151.848481515044, 15674.373025078869, 13853.238071906142, 14337.696893879365, 14399.92800035993, 14637.024001759626, 15074.54776356711, 14055.0, 14203.644908876948, 14329.355179017179, 14606.488772892897, 14769.966102372731, 11469.885301146882, 11677.76644467089, 11741.178117532032, 12006.09954253417, 12224.69438264015, 11839.58561450345, 11951.701207469883, 12075.679188223308, 12115.878841211475, 12156.635300940987], "value": 8250.72146975534, "unit": "score"}], "name": "dromaeo_css", "value": 7540.036831201465, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 116.0, 161.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 109.0, 139.0, 104.0, 106.0, 107.0, 107.0, 136.0, 106.0], "value": 116.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 183.0, 181.0, 184.0, 181.0, 181.0, 183.0, 177.0, 183.0], "value": 181.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 57.0, 86.0, 60.0, 59.0, 58.0, 58.0, 82.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 93.0, 69.0, 66.0, 69.0, 92.0, 67.0, 68.0, 89.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 89.0, 86.0, 89.0, 87.0, 89.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 150.0, 150.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 92.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 84.0, 85.0, 84.0, 84.0, 89.0, 85.0, 86.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 52.0, 54.0, 55.0, 55.0, 54.0, 54.0, 55.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 69.0, 79.0, 74.0, 70.0, 68.0, 68.0, 68.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 84.0, 86.0, 89.0, 92.0, 85.0, 80.0, 81.0, 89.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 151.0, 137.0, 135.0, 135.0, 140.0, 137.0, 165.0, 131.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 157.0, 159.0, 161.0, 169.0, 160.0, 157.0, 159.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 67.0, 68.0, 75.0, 84.0, 82.0, 84.0, 75.0, 65.0], "value": 72.9, "unit": "score"}], "name": "kraken", "value": 1422.3999999999999, "alertThreshold": 2.0}]} 09:59:13 INFO - Return code: 0 09:59:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:59:13 INFO - # TBPL SUCCESS # 09:59:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:59:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:59:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:59:13 INFO - 2016-11-22 09:59:13.211 screenresolution[1652:9811] starting screenresolution argv=screenresolution get 09:59:13 INFO - 2016-11-22 09:59:13.213 screenresolution[1652:9811] Display 0: 1600x1200x32@60 09:59:13 INFO - 2016-11-22 09:59:13.219 screenresolution[1653:9814] starting screenresolution argv=screenresolution list 09:59:13 INFO - Available Modes on Display 0 09:59:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:59:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:59:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:59:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:59:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:59:13 INFO - Intel Iris: 09:59:13 INFO - Chipset Model: Intel Iris 09:59:13 INFO - Type: GPU 09:59:13 INFO - Bus: Built-In 09:59:13 INFO - VRAM (Dynamic, Max): 1536 MB 09:59:13 INFO - Vendor: Intel (0x8086) 09:59:13 INFO - Device ID: 0x0a2e 09:59:13 INFO - Revision ID: 0x0009 09:59:13 INFO - Displays: 09:59:13 INFO - Display: 09:59:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:59:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:59:13 INFO - Display Serial Number: 1600x1200 60 09:59:13 INFO - Main Display: Yes 09:59:13 INFO - Mirror: Off 09:59:13 INFO - Online: Yes 09:59:13 INFO - Rotation: Supported 09:59:13 INFO - Return code: 0 09:59:13 INFO - Running post-action listener: _resource_record_post_action 09:59:13 INFO - [mozharness: 2016-11-22 17:59:13.459059Z] Finished run-tests step (success) 09:59:13 INFO - Running post-run listener: _resource_record_post_run 09:59:13 INFO - Total resource usage - Wall time: 1197s; CPU: 53.0%; Read bytes: 38650880; Write bytes: 493382656; Read time: 381; Write time: 1605 09:59:13 INFO - TinderboxPrint: CPU usage
53.2% 09:59:13 INFO - TinderboxPrint: I/O read bytes / time
38,650,880 / 381 09:59:13 INFO - TinderboxPrint: I/O write bytes / time
493,382,656 / 1,605 09:59:13 INFO - TinderboxPrint: CPU idle
2,239.4 (46.8%) 09:59:13 INFO - TinderboxPrint: CPU system
955.8 (20.0%) 09:59:13 INFO - TinderboxPrint: CPU user
1,594.5 (33.3%) 09:59:13 INFO - TinderboxPrint: Swap in / out
233,615,360 / 0 09:59:13 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 192365056; Write bytes: 185778176; Read time: 17669; Write time: 339 09:59:13 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 36709376; Write bytes: 296524800; Read time: 357; Write time: 1216 09:59:13 INFO - Running post-run listener: _upload_blobber_files 09:59:13 INFO - Blob upload gear active. 09:59:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:59:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:59:14 INFO - (blobuploader) - INFO - Open directory for files ... 09:59:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:59:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:59:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:15 INFO - (blobuploader) - INFO - Done attempting. 09:59:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:59:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:59:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:16 INFO - (blobuploader) - INFO - Done attempting. 09:59:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:59:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:59:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:19 INFO - (blobuploader) - INFO - Done attempting. 09:59:19 INFO - (blobuploader) - INFO - Iteration through files over. 09:59:19 INFO - Return code: 0 09:59:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:59:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:59:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f97b8f612e6f4a14a2f370f4eaa2101f2dd46213c1d4aa49d5c5b52153e90eccec330d6118cff2f492ef30142d80003fa57f1b1c8a7182b4d6a83190d3615f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e7e74840605f16eac49967ee0e37342b34ee039746b87378ec3d6b26abdfa5b4270482553b40dcf103fd870b7d1dbd62ee103047d101062226cd3b3da1c86a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124c6abecf070f405e07ff7bb4386e11955a5144a780360693c173077c216b3999d4d84dd6a2a09b3e02ca44f00a84bd7490d209d1ee741d864211f8489b9a10"} 09:59:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:59:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:59:19 INFO - Contents: 09:59:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f97b8f612e6f4a14a2f370f4eaa2101f2dd46213c1d4aa49d5c5b52153e90eccec330d6118cff2f492ef30142d80003fa57f1b1c8a7182b4d6a83190d3615f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e7e74840605f16eac49967ee0e37342b34ee039746b87378ec3d6b26abdfa5b4270482553b40dcf103fd870b7d1dbd62ee103047d101062226cd3b3da1c86a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124c6abecf070f405e07ff7bb4386e11955a5144a780360693c173077c216b3999d4d84dd6a2a09b3e02ca44f00a84bd7490d209d1ee741d864211f8489b9a10"} 09:59:19 INFO - Running post-run listener: copy_logs_to_upload_dir 09:59:19 INFO - Copying logs to upload dir... 09:59:19 INFO - mkdir: /builds/slave/test/build/upload/logs 09:59:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.900096 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-11-22 09:59:19.796585) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:59:19.804657) ========= 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.3pI3pdU197/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.PtdKs137k3/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/f97b8f612e6f4a14a2f370f4eaa2101f2dd46213c1d4aa49d5c5b52153e90eccec330d6118cff2f492ef30142d80003fa57f1b1c8a7182b4d6a83190d3615f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e7e74840605f16eac49967ee0e37342b34ee039746b87378ec3d6b26abdfa5b4270482553b40dcf103fd870b7d1dbd62ee103047d101062226cd3b3da1c86a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124c6abecf070f405e07ff7bb4386e11955a5144a780360693c173077c216b3999d4d84dd6a2a09b3e02ca44f00a84bd7490d209d1ee741d864211f8489b9a10"} build_url:https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010909 build_url: 'https://queue.taskcluster.net/v1/task/NR2pVHZUSw-fp4zoMyj4fg/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/f97b8f612e6f4a14a2f370f4eaa2101f2dd46213c1d4aa49d5c5b52153e90eccec330d6118cff2f492ef30142d80003fa57f1b1c8a7182b4d6a83190d3615f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e7e74840605f16eac49967ee0e37342b34ee039746b87378ec3d6b26abdfa5b4270482553b40dcf103fd870b7d1dbd62ee103047d101062226cd3b3da1c86a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124c6abecf070f405e07ff7bb4386e11955a5144a780360693c173077c216b3999d4d84dd6a2a09b3e02ca44f00a84bd7490d209d1ee741d864211f8489b9a10"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 09:59:19.841854) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:59:19.842221) ========= 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.3pI3pdU197/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.PtdKs137k3/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005013 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 09:59:19.891772) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:59:19.892081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 09:59:19.892520) ========= ========= Total master_lag: 1.73 =========