builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1487881295.13 results: success (0) buildid: 20170223104622 builduid: 0eddb6d65cc34cb5a631a0d5382c265b revision: 982aaad2e5d766031aa81e7f4366524bdae950f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.132366) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.132827) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.133106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.159036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.159440) ========= 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.NnVSttjAev/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.flJYnFQXYF/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-23 12:21:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2017-02-23 12:21:35 (14.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.304539) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.304909) ========= 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.NnVSttjAev/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.flJYnFQXYF/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.025737 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:35.353551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 12:21:35.353900) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 982aaad2e5d766031aa81e7f4366524bdae950f4 --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-aurora --rev 982aaad2e5d766031aa81e7f4366524bdae950f4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NnVSttjAev/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.flJYnFQXYF/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-23 12:21:35,458 truncating revision to first 12 chars 2017-02-23 12:21:35,458 Setting DEBUG logging. 2017-02-23 12:21:35,458 attempt 1/10 2017-02-23 12:21:35,458 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/982aaad2e5d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-23 12:21:36,769 unpacking tar archive at: mozilla-aurora-982aaad2e5d7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.580670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 12:21:36.958357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:36.958679) ========= script_repo_revision: 982aaad2e5d766031aa81e7f4366524bdae950f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:36.959054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:36.959325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 12:21:36.978538) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-23 12:21:36.978924) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NnVSttjAev/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.flJYnFQXYF/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:21:37 INFO - MultiFileLogger online at 20170223 12:21:37 in /builds/slave/test 12:21:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:21:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:21:37 INFO - {'append_to_log': False, 12:21:37 INFO - 'base_work_dir': '/builds/slave/test', 12:21:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:21:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:21:37 INFO - 'branch': 'Mozilla-Aurora', 12:21:37 INFO - 'buildbot_json_path': 'buildprops.json', 12:21:37 INFO - 'config_files': ('talos/mac_config.py',), 12:21:37 INFO - 'default_actions': ('clobber', 12:21:37 INFO - 'read-buildbot-config', 12:21:37 INFO - 'download-and-extract', 12:21:37 INFO - 'populate-webroot', 12:21:37 INFO - 'create-virtualenv', 12:21:37 INFO - 'install', 12:21:37 INFO - 'run-tests'), 12:21:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:21:37 INFO - 'download_minidump_stackwalk': True, 12:21:37 INFO - 'download_symbols': 'ondemand', 12:21:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:21:37 INFO - 'tooltool.py': '/tools/tooltool.py', 12:21:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:21:37 INFO - '/tools/misc-python/virtualenv.py')}, 12:21:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:21:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:21:37 INFO - 'installer_path': 'installer.exe', 12:21:37 INFO - 'log_level': 'info', 12:21:37 INFO - 'log_name': 'talos', 12:21:37 INFO - 'log_to_console': True, 12:21:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:21:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:21:37 INFO - 'opt_config_files': (), 12:21:37 INFO - 'pip_index': False, 12:21:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:37 INFO - 'cmd': ('bash', 12:21:37 INFO - '-c', 12:21:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:37 INFO - 'enabled': True, 12:21:37 INFO - 'halt_on_failure': False, 12:21:37 INFO - 'name': 'check_screen_resolution'},), 12:21:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:37 INFO - 'cmd': ('bash', 12:21:37 INFO - '-c', 12:21:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:37 INFO - 'enabled': True, 12:21:37 INFO - 'halt_on_failure': False, 12:21:37 INFO - 'name': 'check_screen_resolution'},), 12:21:37 INFO - 'run_cmd_checks_enabled': True, 12:21:37 INFO - 'sps_profile': False, 12:21:37 INFO - 'sps_profile_interval': 0, 12:21:37 INFO - 'suite': 'dromaeojs', 12:21:37 INFO - 'system_bits': '32', 12:21:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:21:37 INFO - 'title': 't-yosemite-r7-0164', 12:21:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:21:37 INFO - 'use_talos_json': True, 12:21:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:21:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:21:37 INFO - 'work_dir': 'build'} 12:21:37 INFO - [mozharness: 2017-02-23 20:21:37.194378Z] Running clobber step. 12:21:37 INFO - Running pre-action listener: _resource_record_pre_action 12:21:37 INFO - Running main action method: clobber 12:21:37 INFO - rmtree: /builds/slave/test/build 12:21:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:39 INFO - Running post-action listener: _resource_record_post_action 12:21:39 INFO - [mozharness: 2017-02-23 20:21:39.635141Z] Finished clobber step (success) 12:21:39 INFO - [mozharness: 2017-02-23 20:21:39.635328Z] Running read-buildbot-config step. 12:21:39 INFO - Running pre-action listener: _resource_record_pre_action 12:21:39 INFO - Running main action method: read_buildbot_config 12:21:39 INFO - Using buildbot properties: 12:21:39 INFO - { 12:21:39 INFO - "project": "", 12:21:39 INFO - "product": "firefox", 12:21:39 INFO - "script_repo_revision": "production", 12:21:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:21:39 INFO - "repository": "", 12:21:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:21:39 INFO - "buildid": "20170223104622", 12:21:39 INFO - "pgo_build": "False", 12:21:39 INFO - "basedir": "/builds/slave/test", 12:21:39 INFO - "buildnumber": 67, 12:21:39 INFO - "slavename": "t-yosemite-r7-0164", 12:21:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:21:39 INFO - "platform": "yosemite_r7", 12:21:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:21:39 INFO - "branch": "mozilla-aurora", 12:21:39 INFO - "repo_path": "releases/mozilla-aurora", 12:21:39 INFO - "revision": "982aaad2e5d766031aa81e7f4366524bdae950f4", 12:21:39 INFO - "stage_platform": "macosx64", 12:21:39 INFO - "builduid": "0eddb6d65cc34cb5a631a0d5382c265b", 12:21:39 INFO - "slavebuilddir": "test" 12:21:39 INFO - } 12:21:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 12:21:39 INFO - Running post-action listener: _resource_record_post_action 12:21:39 INFO - [mozharness: 2017-02-23 20:21:39.640863Z] Finished read-buildbot-config step (success) 12:21:39 INFO - [mozharness: 2017-02-23 20:21:39.641001Z] Running download-and-extract step. 12:21:39 INFO - Running pre-action listener: _resource_record_pre_action 12:21:39 INFO - Running main action method: download_and_extract 12:21:39 INFO - mkdir: /builds/slave/test/build/tests 12:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:39 INFO - trying https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 12:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 12:21:40 INFO - Downloaded 1199 bytes. 12:21:40 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 12:21:40 INFO - Using the following test package requirements: 12:21:40 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:21:40 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 12:21:40 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 12:21:40 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'jsshell-mac.zip'], 12:21:40 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 12:21:40 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 12:21:40 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 12:21:40 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 12:21:40 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 12:21:40 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 12:21:40 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 12:21:40 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:21:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:21:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:21:40 INFO - Fetch https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:21:41 INFO - Expected file size: 16357540 12:21:41 INFO - Obtained file size: 16357540 12:21:48 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:21:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 12:21:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:21:48 INFO - Fetch https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 12:21:49 INFO - Expected file size: 16357540 12:21:49 INFO - Obtained file size: 16357540 12:21:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 12:21:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:21:55 INFO - Fetch https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 12:21:56 INFO - Expected file size: 14403485 12:21:56 INFO - Obtained file size: 14403485 12:21: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')]} 12:21:56 INFO - trying https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:21:56 INFO - Downloading https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 12:21:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 12:21:59 INFO - Downloaded 73936395 bytes. 12:21:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:21:59 INFO - mkdir: /builds/slave/test/properties 12:21:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:59 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:59 INFO - Contents: 12:21:59 INFO - build_url:https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 12:21:59 INFO - Running post-action listener: _resource_record_post_action 12:21:59 INFO - Running post-action listener: set_extra_try_arguments 12:21:59 INFO - [mozharness: 2017-02-23 20:21:59.747258Z] Finished download-and-extract step (success) 12:21:59 INFO - [mozharness: 2017-02-23 20:21:59.747377Z] Running populate-webroot step. 12:21:59 INFO - Running pre-action listener: _resource_record_pre_action 12:21:59 INFO - Running main action method: populate_webroot 12:21:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:21:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:21:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:21:59 INFO - '--tppagecycles', 12:21:59 INFO - '7'], 12:21:59 INFO - 'tests': ['tsvgm']}}, 12:21:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:21:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:21:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['damp', 'tps']}, 12:21:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'tests': ['damp', 'tps']}, 12:21:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['dromaeo_dom']}, 12:21:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['basic_compositor_video']}, 12:21:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:21:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['a11yr', 12:21:59 INFO - 'ts_paint', 12:21:59 INFO - 'tpaint', 12:21:59 INFO - 'sessionrestore', 12:21:59 INFO - 'sessionrestore_no_auto_restore', 12:21:59 INFO - 'tabpaint']}, 12:21:59 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:59 INFO - 'ts_paint', 12:21:59 INFO - 'tpaint', 12:21:59 INFO - 'sessionrestore', 12:21:59 INFO - 'sessionrestore_no_auto_restore', 12:21:59 INFO - 'tabpaint']}, 12:21:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:21:59 INFO - 'ts_paint', 12:21:59 INFO - 'tpaint', 12:21:59 INFO - 'sessionrestore', 12:21:59 INFO - 'sessionrestore_no_auto_restore', 12:21:59 INFO - 'tabpaint']}, 12:21:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:21:59 INFO - 'ts_paint', 12:21:59 INFO - 'tpaint', 12:21:59 INFO - 'sessionrestore', 12:21:59 INFO - 'sessionrestore_no_auto_restore', 12:21:59 INFO - 'tabpaint']}, 12:21:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['a11yr', 12:21:59 INFO - 'ts_paint', 12:21:59 INFO - 'tpaint', 12:21:59 INFO - 'sessionrestore', 12:21:59 INFO - 'sessionrestore_no_auto_restore', 12:21:59 INFO - 'tabpaint']}, 12:21:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['a11yr', 12:21:59 INFO - 'ts_paint', 12:21:59 INFO - 'tpaint', 12:21:59 INFO - 'sessionrestore', 12:21:59 INFO - 'sessionrestore_no_auto_restore', 12:21:59 INFO - 'tabpaint']}, 12:21:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['tsvgx', 12:21:59 INFO - 'tsvgr_opacity', 12:21:59 INFO - 'tart', 12:21:59 INFO - 'tscrollx', 12:21:59 INFO - 'cart', 12:21:59 INFO - 'tsvg_static']}, 12:21:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:59 INFO - 'tsvgr_opacity', 12:21:59 INFO - 'tart', 12:21:59 INFO - 'tscrollx', 12:21:59 INFO - 'cart', 12:21:59 INFO - 'tsvg_static']}, 12:21:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'talos_options': ['--disable-e10s'], 12:21:59 INFO - 'tests': ['tp5o']}, 12:21:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:21:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'talos_options': ['--disable-e10s', 12:21:59 INFO - '--xperf_path', 12:21:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:59 INFO - 'tests': ['tp5n']}, 12:21:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:21:59 INFO - 'talos_options': ['--xperf_path', 12:21:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:59 INFO - 'tests': ['tp5n']}}, 12:21:59 INFO - 'talos.zip': {'path': '', 12:21:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:21:59 INFO - Running post-action listener: _resource_record_post_action 12:21:59 INFO - [mozharness: 2017-02-23 20:21:59.765990Z] Finished populate-webroot step (success) 12:21:59 INFO - [mozharness: 2017-02-23 20:21:59.766125Z] Running create-virtualenv step. 12:21:59 INFO - Running pre-action listener: _resource_record_pre_action 12:21:59 INFO - Running main action method: create_virtualenv 12:21:59 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:21:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:21:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:59 INFO - Using real prefix '/tools/python27' 12:21:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:22:00 INFO - Installing distribute.............................................................................................................................................................................................done. 12:22:03 INFO - Installing pip.................done. 12:22:03 INFO - Return code: 0 12:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:22:03 INFO - Reading from file tmpfile_stdout 12:22:03 INFO - Output received: 12:22:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:22:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:03 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:03 INFO - 'HOME': '/Users/cltbld', 12:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:03 INFO - 'LOGNAME': 'cltbld', 12:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:03 INFO - 'MOZ_NO_REMOTE': '1', 12:22:03 INFO - 'NO_EM_RESTART': '1', 12:22:03 INFO - 'PAGER': '/bin/cat', 12:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:03 INFO - 'PWD': '/builds/slave/test', 12:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:03 INFO - 'SHELL': '/bin/bash', 12:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:03 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:03 INFO - 'USER': 'cltbld', 12:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:03 INFO - 'XPC_FLAGS': '0x0', 12:22:03 INFO - 'XPC_SERVICE_NAME': '0', 12:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:22:04 INFO - Cleaning up... 12:22:04 INFO - Return code: 0 12:22:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:04 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:04 INFO - 'HOME': '/Users/cltbld', 12:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:04 INFO - 'LOGNAME': 'cltbld', 12:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:04 INFO - 'MOZ_NO_REMOTE': '1', 12:22:04 INFO - 'NO_EM_RESTART': '1', 12:22:04 INFO - 'PAGER': '/bin/cat', 12:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:04 INFO - 'PWD': '/builds/slave/test', 12:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:04 INFO - 'SHELL': '/bin/bash', 12:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:04 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:04 INFO - 'USER': 'cltbld', 12:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:04 INFO - 'XPC_FLAGS': '0x0', 12:22:04 INFO - 'XPC_SERVICE_NAME': '0', 12:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:04 INFO - Downloading/unpacking psutil>=3.1.1 12:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:22:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:07 INFO - Installing collected packages: psutil 12:22:07 INFO - Running setup.py install for psutil 12:22:07 INFO - building 'psutil._psutil_osx' extension 12:22:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:22:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:22:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:22:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:22:08 INFO - building 'psutil._psutil_posix' extension 12:22:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:22:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:22:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:22:08 INFO - ^ 12:22:08 INFO - 1 warning generated. 12:22:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:09 INFO - Successfully installed psutil 12:22:09 INFO - Cleaning up... 12:22:09 INFO - Return code: 0 12:22:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:09 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:09 INFO - 'HOME': '/Users/cltbld', 12:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:09 INFO - 'LOGNAME': 'cltbld', 12:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:09 INFO - 'MOZ_NO_REMOTE': '1', 12:22:09 INFO - 'NO_EM_RESTART': '1', 12:22:09 INFO - 'PAGER': '/bin/cat', 12:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:09 INFO - 'PWD': '/builds/slave/test', 12:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:09 INFO - 'SHELL': '/bin/bash', 12:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:09 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:09 INFO - 'USER': 'cltbld', 12:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:09 INFO - 'XPC_FLAGS': '0x0', 12:22:09 INFO - 'XPC_SERVICE_NAME': '0', 12:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:22:12 INFO - Installing collected packages: mozsystemmonitor 12:22:12 INFO - Running setup.py install for mozsystemmonitor 12:22:12 INFO - Successfully installed mozsystemmonitor 12:22:12 INFO - Cleaning up... 12:22:12 INFO - Return code: 0 12:22:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:12 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:12 INFO - 'HOME': '/Users/cltbld', 12:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:12 INFO - 'LOGNAME': 'cltbld', 12:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:12 INFO - 'MOZ_NO_REMOTE': '1', 12:22:12 INFO - 'NO_EM_RESTART': '1', 12:22:12 INFO - 'PAGER': '/bin/cat', 12:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:12 INFO - 'PWD': '/builds/slave/test', 12:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:12 INFO - 'SHELL': '/bin/bash', 12:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:12 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:12 INFO - 'USER': 'cltbld', 12:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:12 INFO - 'XPC_FLAGS': '0x0', 12:22:12 INFO - 'XPC_SERVICE_NAME': '0', 12:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:12 INFO - Downloading/unpacking jsonschema==2.5.1 12:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:22:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:15 INFO - Downloading functools32-3.2.3-2.tar.gz 12:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:22:15 INFO - warning: no files found matching '*.txt' 12:22:15 INFO - no previously-included directories found matching 'build' 12:22:15 INFO - no previously-included directories found matching 'dist' 12:22:15 INFO - no previously-included directories found matching '.git*' 12:22:15 INFO - Installing collected packages: jsonschema, functools32 12:22:15 INFO - Running setup.py install for functools32 12:22:15 INFO - warning: no files found matching '*.txt' 12:22:15 INFO - no previously-included directories found matching 'build' 12:22:15 INFO - no previously-included directories found matching 'dist' 12:22:15 INFO - no previously-included directories found matching '.git*' 12:22:16 INFO - Successfully installed jsonschema functools32 12:22:16 INFO - Cleaning up... 12:22:16 INFO - Return code: 0 12:22:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:16 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:16 INFO - 'HOME': '/Users/cltbld', 12:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:16 INFO - 'LOGNAME': 'cltbld', 12:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:16 INFO - 'MOZ_NO_REMOTE': '1', 12:22:16 INFO - 'NO_EM_RESTART': '1', 12:22:16 INFO - 'PAGER': '/bin/cat', 12:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:16 INFO - 'PWD': '/builds/slave/test', 12:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:16 INFO - 'SHELL': '/bin/bash', 12:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:16 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:16 INFO - 'USER': 'cltbld', 12:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:16 INFO - 'XPC_FLAGS': '0x0', 12:22:16 INFO - 'XPC_SERVICE_NAME': '0', 12:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:22:16 INFO - Cleaning up... 12:22:16 INFO - Return code: 0 12:22:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:16 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:16 INFO - 'HOME': '/Users/cltbld', 12:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:16 INFO - 'LOGNAME': 'cltbld', 12:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:16 INFO - 'MOZ_NO_REMOTE': '1', 12:22:16 INFO - 'NO_EM_RESTART': '1', 12:22:16 INFO - 'PAGER': '/bin/cat', 12:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:16 INFO - 'PWD': '/builds/slave/test', 12:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:16 INFO - 'SHELL': '/bin/bash', 12:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:16 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:16 INFO - 'USER': 'cltbld', 12:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:16 INFO - 'XPC_FLAGS': '0x0', 12:22:16 INFO - 'XPC_SERVICE_NAME': '0', 12:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:16 INFO - Downloading/unpacking blobuploader==1.2.4 12:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:19 INFO - Downloading blobuploader-1.2.4.tar.gz 12:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:22:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:22:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:20 INFO - Downloading docopt-0.6.1.tar.gz 12:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:22:20 INFO - Installing collected packages: blobuploader, requests, docopt 12:22:20 INFO - Running setup.py install for blobuploader 12:22:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:22:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:22:20 INFO - Running setup.py install for requests 12:22:21 INFO - Running setup.py install for docopt 12:22:21 INFO - Successfully installed blobuploader requests docopt 12:22:21 INFO - Cleaning up... 12:22:21 INFO - Return code: 0 12:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:21 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:21 INFO - 'HOME': '/Users/cltbld', 12:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:21 INFO - 'LOGNAME': 'cltbld', 12:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:21 INFO - 'MOZ_NO_REMOTE': '1', 12:22:21 INFO - 'NO_EM_RESTART': '1', 12:22:21 INFO - 'PAGER': '/bin/cat', 12:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:21 INFO - 'PWD': '/builds/slave/test', 12:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:21 INFO - 'SHELL': '/bin/bash', 12:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:21 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:21 INFO - 'USER': 'cltbld', 12:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:21 INFO - 'XPC_FLAGS': '0x0', 12:22:21 INFO - 'XPC_SERVICE_NAME': '0', 12:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:21 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-xNj2Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZoXiIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-FXAy8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-S5neJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-sqwf8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gJkWMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-UiE5Jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-BPgxuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-VJZW7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:22 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ewWTnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-aSOqkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vxlIxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-MSilxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-QGKnjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-4ASIzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-R5Edhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:23 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vJ6RG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:22:23 INFO - Running setup.py install for manifestparser 12:22:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:22:23 INFO - Running setup.py install for mozcrash 12:22:24 INFO - Running setup.py install for mozdebug 12:22:24 INFO - Running setup.py install for mozdevice 12:22:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Running setup.py install for mozfile 12:22:24 INFO - Running setup.py install for mozhttpd 12:22:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Running setup.py install for mozinfo 12:22:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Running setup.py install for mozInstall 12:22:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:22:24 INFO - Running setup.py install for mozleak 12:22:25 INFO - Running setup.py install for mozlog 12:22:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for moznetwork 12:22:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozprocess 12:22:25 INFO - Running setup.py install for mozprofile 12:22:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozrunner 12:22:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:22:25 INFO - Running setup.py install for mozscreenshot 12:22:26 INFO - Running setup.py install for moztest 12:22:26 INFO - Running setup.py install for mozversion 12:22:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:22:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:22:26 INFO - Cleaning up... 12:22:26 INFO - Return code: 0 12:22:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:26 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:26 INFO - 'HOME': '/Users/cltbld', 12:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:26 INFO - 'LOGNAME': 'cltbld', 12:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:26 INFO - 'MOZ_NO_REMOTE': '1', 12:22:26 INFO - 'NO_EM_RESTART': '1', 12:22:26 INFO - 'PAGER': '/bin/cat', 12:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:26 INFO - 'PWD': '/builds/slave/test', 12:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:26 INFO - 'SHELL': '/bin/bash', 12:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:26 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:26 INFO - 'USER': 'cltbld', 12:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:26 INFO - 'XPC_FLAGS': '0x0', 12:22:26 INFO - 'XPC_SERVICE_NAME': '0', 12:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-WXSAm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-jirTnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:26 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-GmtBH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Ppoluf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-tyzE8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-SxZiGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Ve5q3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-oPC8F_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-bwG8qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-50dULC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-GrBFhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-lIOaoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:27 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-6VGUlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:28 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-H6Zpr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:28 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-MHJHd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:28 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gLKHr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:28 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-LM2s1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:22:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:31 INFO - Downloading blessings-1.6.tar.gz 12:22:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:22:31 INFO - Installing collected packages: blessings 12:22:31 INFO - Running setup.py install for blessings 12:22:31 INFO - Successfully installed blessings 12:22:31 INFO - Cleaning up... 12:22:31 INFO - Return code: 0 12:22:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:22:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:31 INFO - Reading from file tmpfile_stdout 12:22:31 INFO - Current package versions: 12:22:31 INFO - blessings == 1.6 12:22:31 INFO - blobuploader == 1.2.4 12:22:31 INFO - docopt == 0.6.1 12:22:31 INFO - functools32 == 3.2.3-2 12:22:31 INFO - jsonschema == 2.5.1 12:22:31 INFO - manifestparser == 1.1 12:22:31 INFO - mozInstall == 1.12 12:22:31 INFO - mozcrash == 1.0 12:22:31 INFO - mozdebug == 0.1 12:22:31 INFO - mozdevice == 0.49 12:22:31 INFO - mozfile == 1.2 12:22:31 INFO - mozhttpd == 0.7 12:22:31 INFO - mozinfo == 0.9 12:22:31 INFO - mozleak == 0.1 12:22:31 INFO - mozlog == 3.4 12:22:31 INFO - moznetwork == 0.27 12:22:31 INFO - mozprocess == 0.24 12:22:31 INFO - mozprofile == 0.28 12:22:31 INFO - mozrunner == 6.13 12:22:31 INFO - mozscreenshot == 0.1 12:22:31 INFO - mozsystemmonitor == 0.3 12:22:31 INFO - moztest == 0.8 12:22:31 INFO - mozversion == 1.4 12:22:31 INFO - psutil == 3.1.1 12:22:31 INFO - requests == 1.2.3 12:22:31 INFO - wsgiref == 0.1.2 12:22:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 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 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:32 INFO - 'HOME': '/Users/cltbld', 12:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:32 INFO - 'LOGNAME': 'cltbld', 12:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:32 INFO - 'MOZ_NO_REMOTE': '1', 12:22:32 INFO - 'NO_EM_RESTART': '1', 12:22:32 INFO - 'PAGER': '/bin/cat', 12:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:32 INFO - 'PWD': '/builds/slave/test', 12:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:32 INFO - 'SHELL': '/bin/bash', 12:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:32 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:32 INFO - 'USER': 'cltbld', 12:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:32 INFO - 'XPC_FLAGS': '0x0', 12:22:32 INFO - 'XPC_SERVICE_NAME': '0', 12:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:22:32 INFO - Cleaning up... 12:22:32 INFO - Return code: 0 12:22:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104cb1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f7948f460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, '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.NnVSttjAev/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.flJYnFQXYF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:33 INFO - 'HOME': '/Users/cltbld', 12:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:33 INFO - 'LOGNAME': 'cltbld', 12:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:33 INFO - 'MOZ_NO_REMOTE': '1', 12:22:33 INFO - 'NO_EM_RESTART': '1', 12:22:33 INFO - 'PAGER': '/bin/cat', 12:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:33 INFO - 'PWD': '/builds/slave/test', 12:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:33 INFO - 'SHELL': '/bin/bash', 12:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:33 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:33 INFO - 'USER': 'cltbld', 12:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:33 INFO - 'XPC_FLAGS': '0x0', 12:22:33 INFO - 'XPC_SERVICE_NAME': '0', 12:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:22:33 INFO - Cleaning up... 12:22:33 INFO - Return code: 0 12:22:33 INFO - Running post-action listener: _resource_record_post_action 12:22:33 INFO - Running post-action listener: _start_resource_monitoring 12:22:33 INFO - Starting resource monitoring. 12:22:33 INFO - [mozharness: 2017-02-23 20:22:33.527767Z] Finished create-virtualenv step (success) 12:22:33 INFO - [mozharness: 2017-02-23 20:22:33.528733Z] Running install step. 12:22:33 INFO - Running pre-action listener: _resource_record_pre_action 12:22:33 INFO - Running main action method: install 12:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:33 INFO - Reading from file tmpfile_stdout 12:22:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:33 INFO - Reading from file tmpfile_stdout 12:22:33 INFO - Output received: 12:22:33 INFO - Usage: mozinstall [options] installer 12:22:33 INFO - Options: 12:22:33 INFO - -h, --help show this help message and exit 12:22:33 INFO - -d DEST, --destination=DEST 12:22:33 INFO - Directory to install application into. [default: 12:22:33 INFO - "/builds/slave/test"] 12:22:33 INFO - --app=APP Application being installed. [default: firefox] 12:22:33 INFO - mkdir: /builds/slave/test/build/application 12:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:22:54 INFO - Reading from file tmpfile_stdout 12:22:54 INFO - Output received: 12:22:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:22:54 INFO - Running post-action listener: _resource_record_post_action 12:22:54 INFO - [mozharness: 2017-02-23 20:22:54.210331Z] Finished install step (success) 12:22:54 INFO - [mozharness: 2017-02-23 20:22:54.210470Z] Running run-tests step. 12:22:54 INFO - Running pre-action listener: _resource_record_pre_action 12:22:54 INFO - Running main action method: run_tests 12:22:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:22:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:22:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:22:54 INFO - 2017-02-23 12:22:54.225 screenresolution[1631:7466] starting screenresolution argv=screenresolution get 12:22:54 INFO - 2017-02-23 12:22:54.227 screenresolution[1631:7466] Display 0: 1600x1200x32@60 12:22:54 INFO - 2017-02-23 12:22:54.233 screenresolution[1632:7469] starting screenresolution argv=screenresolution list 12:22:54 INFO - Available Modes on Display 0 12:22:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:22:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:22:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:22:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:22:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:22:54 INFO - Intel Iris: 12:22:54 INFO - Chipset Model: Intel Iris 12:22:54 INFO - Type: GPU 12:22:54 INFO - Bus: Built-In 12:22:54 INFO - VRAM (Dynamic, Max): 1536 MB 12:22:54 INFO - Vendor: Intel (0x8086) 12:22:54 INFO - Device ID: 0x0a2e 12:22:54 INFO - Revision ID: 0x0009 12:22:54 INFO - Displays: 12:22:54 INFO - Display: 12:22:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:22:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:22:54 INFO - Display Serial Number: 1600x1200 60 12:22:54 INFO - Main Display: Yes 12:22:54 INFO - Mirror: Off 12:22:54 INFO - Online: Yes 12:22:54 INFO - Rotation: Supported 12:22:54 INFO - Return code: 0 12:22:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:22:54 INFO - Python 2.7.3 12:22:54 INFO - Return code: 0 12:22:54 INFO - grabbing minidump binary from tooltool 12:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:54 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 0x104a8fd30>, '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 0x1049b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a84be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:22:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:22:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:22:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:22:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:22:54 INFO - Return code: 0 12:22:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:22:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:22:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:22:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:22:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:22:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NnVSttjAev/Render', 12:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:54 INFO - 'HOME': '/Users/cltbld', 12:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:54 INFO - 'LOGNAME': 'cltbld', 12:22:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:22:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:54 INFO - 'MOZ_NO_REMOTE': '1', 12:22:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:22:54 INFO - 'NO_EM_RESTART': '1', 12:22:54 INFO - 'PAGER': '/bin/cat', 12:22:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:54 INFO - 'PWD': '/builds/slave/test', 12:22:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:54 INFO - 'SHELL': '/bin/bash', 12:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.flJYnFQXYF/Listeners', 12:22:54 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:54 INFO - 'USER': 'cltbld', 12:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:54 INFO - 'XPC_FLAGS': '0x0', 12:22:54 INFO - 'XPC_SERVICE_NAME': '0', 12:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:22:54 INFO - mozversion application_buildid: 20170223104622 12:22:54 INFO - mozversion application_changeset: 982aaad2e5d766031aa81e7f4366524bdae950f4 12:22:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:22:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:22:54 INFO - mozversion application_name: Firefox 12:22:54 INFO - mozversion application_remotingname: firefox-dev 12:22:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:22:54 INFO - mozversion application_vendor: Mozilla 12:22:54 INFO - mozversion application_version: 53.0a2 12:22:54 INFO - mozversion platform_buildid: 20170223104622 12:22:54 INFO - mozversion platform_changeset: 982aaad2e5d766031aa81e7f4366524bdae950f4 12:22:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:22:54 INFO - mozversion platform_version: 53.0a2 12:22:54 INFO - using testdate: 1487881374 12:22:54 INFO - actual date: 1487881374 12:22:54 INFO - starting webserver on 'localhost:49247' 12:22:54 INFO - SUITE-START | Running 2 tests 12:22:54 INFO - TEST-START | dromaeo_css 12:22:54 INFO - operating with platform_type : mac_ 12:22:54 INFO - Initialising browser for dromaeo_css test... 12:22:54 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmprjMj1g/profile http://localhost:49247/getInfo.html) 12:22:57 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 12:22:57 INFO - PROCESS | 1642 | colorDepth:24 12:22:57 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 12:22:57 INFO - PROCESS | 1642 | __metrics 12:22:57 INFO - PROCESS | 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:22:57 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:22:58 INFO - TEST-INFO | 1642: exit 0 12:22:58 INFO - Browser initialized. 12:22:58 INFO - Running cycle 1/1 for dromaeo_css test... 12:22:58 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmprjMj1g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:10 INFO - PROCESS | 1644 | RSS: Main: 212869120 12:23:10 INFO - PROCESS | 1644 | 12:26:13 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 12:26:16 INFO - PROCESS | 1644 | RSS: Main: 298942464 12:26:16 INFO - PROCESS | 1644 | 12:27:59 INFO - PROCESS | 1644 | ************************* 12:27:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 12:27:59 INFO - PROCESS | 1644 | 12:27:59 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:27:59 INFO - PROCESS | 1644 | Full stack: 12:27:59 INFO - PROCESS | 1644 | ************************* 12:29:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 12:29:19 INFO - PROCESS | 1644 | RSS: Main: 355123200 12:29:19 INFO - PROCESS | 1644 | 12:32:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 12:32:22 INFO - PROCESS | 1644 | RSS: Main: 325169152 12:32:22 INFO - PROCESS | 1644 | 12:35:25 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 12:35:25 INFO - PROCESS | 1644 | RSS: Main: 349597696 12:35:25 INFO - PROCESS | 1644 | 12:38:29 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 12:38:30 INFO - PROCESS | 1644 | RSS: Main: 315879424 12:38:30 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 12:41:33 INFO - PROCESS | 1644 | RSS: Main: 462659584 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | __start_tp_report 12:41:33 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 12:41:33 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 12:41:33 INFO - PROCESS | 1644 | |i|pagename|runs| 12:41:33 INFO - PROCESS | 1644 | |0;dojo.html;3273.6562660920604;3276.1154488288157;3333.100062982996;3340.7661463697536;3386.0349800306917;5614.185943038257;5671.914921276184;5680.176374425706;5681.147827825828;5715.942550628126;5641.605087643867;5712.771489140429;5726.541876649869;5728.341240757314;5779.537636989041;4857.611391088704;5166.354205724294;5196.662216955905;5232.293640358541;5259.526642602165;5078.466760990098;5128.8974220515565;5131.153359695646;5282.709450980195;5298.36419629645;4880.341153944208;5126.71803050832;5175.146100893367;5180.922286165711;5268.841934741964;4754.358161648185;5241.8427447176455;5271.578273738091;5271.868203294948;5302.840914772563;5021.171506701427;5120.948790512121;5177.301064356301;5206.323177986876;5213.2701421800975;5367.114426119686;5478.342598888119;5483;5521.558275338003;5588.273524441837;6002.519798416116;6072.028475443908;6075;6098;6112.419320164578;6087.939120608826;6159.938400615982;6161.938380616226;6165.876682466325;6171.290301615327;5561.193626924073;5645.915311270334;5659.547236221092;5722.914156287659;5733.168690539848;6190.0405437157315;6248.156498872685;6267.71795269214;6269.404406581368;6276.403741644536;6053.939460605426;6148.077788331786;6151.108089327023;6170.228721409824;6184.5052395808225;6116.26604807426;6221.129041934133;6222.564420490522;6296.464800492009;6306.684665766693;6102.511799056065;6103.664298463538;6139.627825652554;6148.1085242639565;6155.138280640714;6247.468965137923;6248.781292654735;6249.1563638908165;6268.780592679234;6277.466415354655;4908.993656300449;4917.459079501252;4953.777080031407;4969.229769385751;4993;4340.674449416307;4377.190219809346;4383.276759334674;4446.866594002185;4449.666275029385;5676.8864622707315;5687.630304030225;5694.515966142924;5696.829095127153;5700.458456446631;5862.501687356537;5974.253218347707;6202.193714817045;6206.379362063757;6244.875922333933;5720.227769251181;5753.194552762617;5762.394948530344;5784;5800.9129863052085;5451;5451.236826844166;5451.536619387396;5465.699386533699;5467.398586155519;6192.845178870564;6220.688965551613;6222.533310001768;6226.221722284715;6269;6294.590851594632;6299.937000629935;6307.30619631831;6312.873742525032;6326.493880489642;6223.3465486123305;6283.340249273943;6289.591176573691;6290.842728931813;6297.55917085818;5098.260752190838;6006.249218847644;6106.114613380946;6206.813795586139;6207.224096987877;6098.634081955097;6163;6209.875802484015;6253.5622506424115;6313.1477250572425;6116.29662588808;6119.204503414706;6124.663143527148;6130.724117414817;6135.815925522242;5918.674472904032;6023.45788879003;6033.396660333931;6084.087386892002;6111.96944015277;5798.913016304843;5888.882222355443;5925.377835327228;5943.019401798655;6017.729202185825;6090.543209259235;6132.63204207749;6134.8159555213415;6161.291451483136;6167.876642467215;5742.483176514133;5793.420657934173;5806.941930580809;5882.970585147047;5893.675847828411 12:41:33 INFO - PROCESS | 1644 | |1;ext.html;470.4519235091119;496.9105560999021;501.58869726823997;503.8060346766494;512.5873671694286;31306.217344566398;40280.1943961121;40338.59661403385;40428.39357409638;40469.78590642278;41683.791581042125;42104.15791684164;42177;42188.78905605468;42225;8331.916680833205;8972.327075469333;9165.175134237918;9170;9199.448033118;8334.249917507423;10962.671119866376;11206.719832004224;11230.270032447906;11406.828897566544;11152.275102118378;11158.832617510743;11207.66377008687;11221.831672524919;11225.32648041116;8376.623051962677;8601.741947741577;11044.668659940215;11105.55577776888;11124.332540047622;10720.303180293256;11020.173486988508;11022.44887755609;11187.832182517268;11243.943780281128;39400.60599394027;39713.20573588541;39744.80127599373;39887.4016889744;39892;3976.284268831609;3985.920281594381;3999.8000099994883;4018;4027.6979226558124;28638;28685.71314286872;28699.713002869914;30378.392432151235;30490.085297441114;3069.8465076746074;3252.9512057319157;3358.6809253120914;3369.0903456066726;3369.882054128094;430744;433688;434142;435340;437308;55986;60094.49763755941;60130.398696013355;60277;60329.39670603326;61836.07245891315;61908.69045654833;61937;61967.38032619706;61978.070328945105;15957.760633590506;16063.518094457186;16143.950643208154;16495.587610309838;16498;11581.42092895352;11818.290902545874;11841.526338946345;12792.2964236966;12862.292573908524;17813.05435524221;21673.783262167177;21787.455313617287;22367.66448503274;22452.438689032904;8560.357973152039;8722.563871806384;8752.8687069694;8799.824003519894;8833.867491987625;779.8302546180729;855.5935930432995;872.4721543466238;906.6645341223658;911.9498427586547;25272.36819079501;29453.26366840803;29897.953571625323;29901.850490747405;30674.53988190179;1044.1177205261542;1047.450088703437;1081.2485322700804;1083.160550573312;1187.0681515010847;14914.627134321729;15348.539543813704;15456.918015739015;15463.690726185414;15737.527874163794;609925;613994;614543;615150;616408;59232.70383648054;59817;59867.401325987055;59899.40100599025;60106.09840852391;1401.8107555480083;1442.985570144285;1449.7825326201155;1453.4476898778184;1459.109942934809;26998.140568995983;28772.280692982844;28969.275768105967;29095.12714618565;29567.704322957357;49922.00155996932;50262.246066308304;50293.74853125711;50355.74822125866;50424.49575504198;464983;539948;541038;541340;541686;25834.483310334064;26284.605730914434;26437.60343594809;42642;43015.139697206505;41207.1758564833;41214.96962575959;42732;42759.7862010688;43156;34674.066569176866;35040;35073.82463087668;36058;36130.8193459031;30737;34233;34604.82697586496;34714.9585512435;35751.46372804462;34167.65832341645;34850.82574587111;34948.47577286394;35004.9498515045;35042.123946901535;24889;27015.324616884736;28882.566761498176;29217.41565168727;31617.84191079122;22499.338812456932;23348.59908405501;23420.648690269318;23451.76548234496;23841.761582384646 12:41:33 INFO - PROCESS | 1644 | |2;jquery.html;334.4565081742169;367.7977112588079;386.4300157268028;399.1318881432883;425.1900130251871;14667.486637967673;15085.170315632637;17175.57061073472;19986.401087912967;20512.794872051276;17204.82795172048;20799.584008319824;21347.35957921261;21417.892910535465;21551.13795448184;5638.492535671789;5811.215485909401;6038.667873266991;6049.606775559574;6057.121717350981;5795.507381872546;5974.641521508702;5986.670733109699;5993.670348130851;6012.128241404994;3883.2427676603074;4082.6121518455698;7292.7447539336135;7679.462437629368;7719.07371115467;6417.582857114273;7576.772696819076;7667.003289572375;7758.806029849271;7769.873368361556;7183.89224161638;7590.8481830363635;7596.278353556403;7600.429967752385;7608.733694320672;29263.561046584317;29718.851405743048;29863.85068074646;29974.250643733863;30609.846950765103;2921;2935.6036935013713;2963.2147480917574;3052.1759125036115;3094.9941269087412;15914.52256432309;16554.83445165545;16569.591584715312;17139.57151071221;17686.734698979526;1824.9087545622665;1878.8872667639985;1880.2356508986081;1883.4067268810281;1909.598984213317;152719;154527;158185;159022.99999999884;162359.1882040594;18722;22767.658485122738;23157.42106447352;23273.53452930932;23403.648945265835;41006.79496602498;41074.9731256721;41945.58054419478;42031.57968420276;42194.57805421968;7146.821329466701;8537.402381833337;9009.279257659502;9078.273738100936;9138.314626403046;7134;8498.66005359791;8819.91180088191;8977.32670049749;8992;4889.217725163957;4891.461939186686;4893.853184404474;4907.312976183266;4965.950340496549;1875.37175046361;1892.0417368024305;2058.3619078085544;2058.5265388960624;2068.7931206879493;962.4465932089076;992.6178421307849;1006.5772375602264;1023.3677284730842;1057.962971296001;21161.89419052895;21180.576388472145;21305.14779408806;21317.893410532848;21723.23968661121;1018.277023313441;1030.299396410448;1057.2176589324;1059.3855563773147;1066.9573217071231;10515.737106572244;10880.02869983751;10905.509252083675;10994.95547922946;11048;412595;412679;734477;755565;776512;83286.58356708298;96815;124251;139048;155010;932.2774849491698;947.6920000999703;970.2674480766975;971.3977334052933;972.7276362618478;18396.448106556825;18770.49836013143;18881.811181888006;19356.61286774285;19558.413247602595;56497.43502564922;57132;57571.71214143903;57645.84708305894;59328.70335648294;82286;83292;84628.73056904029;85969;86008.56995715231;8063.395245356739;8131.2681858633005;8197.877031844646;8216.589170541329;8487.787805304917;11849.466773995364;12508.186967847061;12530.74938501243;12711.364431778558;12776.425060872098;10792.730181745204;10924.2899211551;10951.233413661286;11025.338479691245;11086.722831929266;9704.75738106553;10102.545385457524;10633.8936610636;10670.413127277763;11056;10594.735131621772;10931.234813562975;10958.232923695265;11127.332360058423;11248.850321909878;7957.602119893866;8065.87901181471;8284.668613255644;8600;8616.698415555426;9237;9531.332806703464;10092.798144037224;11271.211015228855;11601.01391381742 12:41:33 INFO - PROCESS | 1644 | |3;mootools.html;1408.9225092619909;1413.2227274998747;1418.8652078052585;1423.7579611466047;1430.3491911180413;8222.588870556478;8612;8627.784305392368;8629.137086291375;8639.827203455934;7527.811804704871;7809.336206422447;7816.491928024674;8063.435559510836;8444.366672499556;2281.3954302109955;2324.2562380038394;2325.104834638668;2326.7789559991775;2333;2295.0819672131115;2329.615613423783;2332.3819187915215;2344.8593084414906;2348.6477028445784;2041.7856125106873;2137.1985505435464;2279.943001424969;2316.293530473205;2321.7446080931086;2096.413004358782;2107.6417009108413;2118.142152428269;2248.325502349289;2318.802901753353;2343.203310874311;2344.730356009047;2349.271725765001;2354.81161507079;2355.599548076814;8438.662453501853;8520.275776558938;8529.744107676717;8539.957300213522;8547.188017138362;5074.568661663763;5236.319278493809;5238.345206849144;5247.763850626731;5258.92111618326;2921.4011127718977;3346.380919529895;3507.8070706111153;3555.591107022674;3556.1643013891717;1131.5586921100778;1186.4483015397882;1226.7730469863016;1229.1457437081233;1281.0904257976847;11062.834057489144;11170;11193.608223712132;11197.776044479066;11231.494582743715;4497.325401189848;4673.322368256584;4730.432348118248;4746.501617330131;4747.454042785055;9696.175825054808;9701.757456063513;9712.660056897974;9714.854277185848;9715.125638692552;5278.788848446096;5311.628186027018;5311.814086506954;5329.546988505943;5332.706701131475;4199.118185181146;4516.9774151129695;4708.646851486153;4805.927911081337;4835.032993401333;3810.8856734298015;3873.786941718176;3874.0629685157423;4007.719459637856;4016.9397459038137;1087.9129669626411;1219.7865373559769;1225.4669218889812;1232.7534493101416;1237.10928131745;786.8072322281078;874.6545114679666;882.4308321132834;908;942.9104235097655;5527.668339899619;5575.135853942646;5634.887302253932;5700.116481945305;5862.472377486047;958.6884262614678;958.8274110660009;960.1790469148917;971.2375785008876;1006.4464544500642;3399.5920489541413;3528.347255757693;3695.4826324314618;3713.442983552489;3741.644543768392;8008.199180081945;10261.179105671528;10362.740931476623;10376.688699339033;10387.740306492247;10062.094411502854;10103.646372376932;10313;10315.587376504856;10316.845247321296;7564.092308922965;7692.807679807937;7693.422993275415;7697.653605587762;7712.92287077122;4525.932111018403;5156.432792392909;5163.528394407575;5199.4020687621405;5353.357597088374;9031.63873445055;9080.591373388346;9210.217131543626;9327.766805829908;9387.671431499864;9148.451092934445;9344.15902568772;9459.195968342769;9494.85757713621;9510;6323.177986861679;6556.082148499309;6613.834654133685;6832.111825462658;6979.546329488567;7584;7633.847323053396;7998.360131189374;8019.03771547418;8112.391570632344;7148.785536433916;7268;7280.053593033083;7311.561306321638;7392.112946446461;7025.22503611081;7061.152661680426;7065.505414620927;7099;7150.998860159478;6340.1440805490665;6349.68251587428;6374.426301632875;6444.903326450013;6453.128827608401;4901.705897646153;4959.57843583285;4971.9502804972935;4988.376452943382;5027.918997415573;4390.538993405647;4864.562189402971;4896.485868983706;4917.459079501324;5130.435652078343 12:41:33 INFO - PROCESS | 1644 | |4;prototype.html;318.56833989943624;328.4527107241788;364.99452508212363;370.09142555027404;379.40622925122165;4825.638077144211;5261.316028916241;5286.709230992294;5342.706151161679;5389.003034438631;4642.837500687477;4977.83020990068;5248.291480650111;5278.970600732859;5317.218731724867;3138.6259770746424;3512.2273099918107;4194.203101410738;4203.180379825935;4206.284931561642;3382.30662714143;3563.3942229820864;4039.434479172918;4131.277026520362;4138.730982486129;3287.342531493699;4096.733712308705;4103.9384409233735;4124.587541245882;4134.0698342872765;3778.452124441968;4132.690048246363;4156.875293741193;4158.667306615479;4161.83352665893;3099.5040793473163;3129.9687003129907;3687.1519550503267;3697.077519689773;3715.64701353371;4413.955860441387;4750.168720473903;4795.6882802618065;5066.949330506684;5117.130087885032;3829.75532951789;4415.337699345092;4564.612007979293;4591.51789062147;4614.423197100363;4731.503192164808;5155.329807125087;5345.946540534623;5583.553315734732;5639.6334238274385;3174.507951267557;3396.915077123067;3769.4345848122725;3800.619938006205;3853.7880416577077;5262.10544207478;5491.395946445888;5528.5577153828;5696.572757043238;5762.135679648044;3698.9815050925113;4168.18720349535;4173.269677806372;4183.163367326486;4186.623203911662;3601.1357274254515;4233.872983810491;4239.703220774516;4271.295236285978;4292.313229883203;4088.2845502037026;4227.598378154071;4617.930731039037;4799.36860929198;4827.275908613736;4901.460839307673;5144.6655967362;5566.276384070126;5588.720563971785;5634.1830434586755;1251.1992324912064;1297.2864924291603;1305.8694130587055;1319.511780641169;1375.7729974554086;936.5244536940783;939.3518472254123;979.2264111352094;994.1996692662332;1005.8943810899897;3851.730378873452;4227.450431443954;4359.629431498358;4820.927686084711;4883.7778810641075;4403.559644035635;4824.131656301829;5183.974080129575;5358.660334916271;5449.782008719607;4179.519355274121;4625.236835922103;4820.903581928412;4825.324454576362;4875.68308059975;2436.9634455483183;2681.5843544250674;2853.4721076600895;2854.84298363592;2886.466003789306;4858.82994095205;5114.442778610694;5660.915086273709;5667.676252736441;5681.431856814285;5372.260147324892;5382.811601593926;5487.42382049887;5491.368492623399;5619.269494965628;5608.775648973995;5624.353199382042;5630.930123276647;5634.661920284796;5656.37779844225;5441.646292990943;5457.508824205682;5472.835814925558;5479.780808767764;5505.697186654773;4187.890773256622;4765.356676848581;5304.469553044664;5325.721826761472;5345.786168553214;5071.340725705634;5080.771365288645;5315.123004704181;5447.645903016291;5776.42235776419;3666.3400587894434;3697.48235247071;3750.306193354238;3765.2657731743143;3925.823337949742;4890.755462226945;5631.352394474688;5702.914456283076;5715.599908006399;5737.885242295048;4822.638302127284;5288.629795914249;5520.343896830855;5533.5849811263515;5562.3881373048125;4372.978135109304;4981.6014718823135;5099.69401835891;5101.642884998015;5352.598555108305;4786.425628924551;4788.922492439173;5234.168041185555;5245.763940622757;5361.4370491099435;5016.322796422436;5083.212102124176;5456.7544460498575;5512.4211957745465;5540.889182216414;4917.85246442593;5356.589534709475;5437.864053398696;5451.519558600748;5458.61789674719 12:41:33 INFO - PROCESS | 1644 | |5;yui.html;659.7728225500571;716.3409663109938;757.9242075792422;805.3959530352236;808.8988876390451;14106.71786564268;15724.03449568804;15770.132642704646;15773.921130394347;15777.526674199793;14214.147151170937;14315.284235788195;14462.276886155676;14490.710185796277;14499.710005799878;848.9617967191491;861.6338056326052;873.8077622922979;877.2236570634971;880.3001613717079;862.5213006781233;875.8248350329927;877.4209022045451;877.5656050255133;877.7717793373737;927.1331305229631;929.8512238041915;930.1675000874213;930.8929473110611;931.12009151352;922.4373132389237;923.3813345058827;924.7272054743867;926.5691453474133;927.6984979881531;684.650828077679;685.0580451878667;687.669918439147;688;689.7965100295424;14941.17823519706;14997.775033374508;15146.545603631908;15543.922280388639;15846.128462934534;13925.373358198905;14079.648008799759;14131.152130872078;14172.2608932597;14209.36057877398;11487.655370338904;11598.304101753922;11764.882351176464;11907.702307442296;12301.138920275584;3503.4919936609303;3651.8539258429632;3673.430618254175;3829.942550861739;3838.366669499529;108119.99999999921;109228;110777;110837.78324433466;114158;10610.946945265225;10655.520501577448;10676.466176691134;10735.409552474688;11078.612248571422;22514.887425562767;22553.77446225517;22558.436039098826;22568.435789105402;22580.435489112577;7515.736949206752;7661.846763064707;7665.578393188428;7728.690852365955;7796.142023432935;4163.958360416357;4206.26390381682;4256.787160641956;4324.0919406924895;4475.686701930898;3479.1823921378455;3568.286342731464;3572.5355703758346;3597.478365636968;3599.2981368632863;596.4661627843143;603.1917230910601;603.2790814976062;604.9274087109575;605.3159929279885;630.5145038320468;632.585656395062;659.3966520633567;671.3554987212233;685.8902575587982;9169.128932751379;9325.300602454843;9627.652128701988;9916.603335866628;10046.69859904204;639.4228393536555;690.6546726636682;694.8947016753654;701.3547536266545;702.6592102830124;4613.930791038137;4677.78377621814;4899.608031357483;4923.384576927884;4924.212126059813;98755;105498;105920.47039764906;106136;106514;90932.54533727422;95045;95913.0408695918;96096;97525.04949900962;81125.59437202776;82441;83164.07751001831;84814.15185848186;86134.13865861262;9589.472579008221;9596.95201523988;9618.807623847624;9631.277654175825;9717.805643887225;51538.74230628823;52147;52213.7389313051;52515.47484525259;53140.73429632827;68972;69529;69769.9534506993;70062.29937700558;72248;11311.208215424842;11431.314121152758;11521.827172592586;11525.193236473366;12094.334811585448;11998.340091295062;12043.277403355827;12244.204126731735;12786.744265114832;13270.004749643622;11518.481668324777;11983.460744266355;12009.939950300193;12218.083643726934;12238.571649992564;11809.940950295193;11827;12064.82862516035;12397.93801030989;12625;11343.792430204086;11564.710882228012;11730.061595072202;11919.582814601446;12030.458629361525;9718.70843874685;9769.804603908024;9941.25440591944;10167.056581750168;10506.789864202521;7533.80777616089;9652.855207171757;9678.564464598969;9705.563249653924;9888.901110988798 12:41:33 INFO - PROCESS | 1644 | __end_tp_report 12:41:33 INFO - PROCESS | 1644 | __start_cc_report 12:41:33 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,2197 12:41:33 INFO - PROCESS | 1644 | __end_cc_report 12:41:33 INFO - PROCESS | 1644 | __startTimestamp1487882493632__endTimestamp 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | ------- Summary: start ------- 12:41:33 INFO - PROCESS | 1644 | Number of tests: 6 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5703.22 Median:5888.88 stddev:640.79 (10.9%) stddev-sans-first:613.76 12:41:33 INFO - PROCESS | 1644 | Values: 3273.7 3276.1 3333.1 3340.8 3386.0 5614.2 5671.9 5680.2 5681.1 5715.9 5641.6 5712.8 5726.5 5728.3 5779.5 4857.6 5166.4 5196.7 5232.3 5259.5 5078.5 5128.9 5131.2 5282.7 5298.4 4880.3 5126.7 5175.1 5180.9 5268.8 4754.4 5241.8 5271.6 5271.9 5302.8 5021.2 5120.9 5177.3 5206.3 5213.3 5367.1 5478.3 5483.0 5521.6 5588.3 6002.5 6072.0 6075.0 6098.0 6112.4 6087.9 6159.9 6161.9 6165.9 6171.3 5561.2 5645.9 5659.5 5722.9 5733.2 6190.0 6248.2 6267.7 6269.4 6276.4 6053.9 6148.1 6151.1 6170.2 6184.5 6116.3 6221.1 6222.6 6296.5 6306.7 6102.5 6103.7 6139.6 6148.1 6155.1 6247.5 6248.8 6249.2 6268.8 6277.5 4909.0 4917.5 4953.8 4969.2 4993.0 4340.7 4377.2 4383.3 4446.9 4449.7 5676.9 5687.6 5694.5 5696.8 5700.5 5862.5 5974.3 6202.2 6206.4 6244.9 5720.2 5753.2 5762.4 5784.0 5800.9 5451.0 5451.2 5451.5 5465.7 5467.4 6192.8 6220.7 6222.5 6226.2 6269.0 6294.6 6299.9 6307.3 6312.9 6326.5 6223.3 6283.3 6289.6 6290.8 6297.6 5098.3 6006.2 6106.1 6206.8 6207.2 6098.6 6163.0 6209.9 6253.6 6313.1 6116.3 6119.2 6124.7 6130.7 6135.8 5918.7 6023.5 6033.4 6084.1 6112.0 5798.9 5888.9 5925.4 5943.0 6017.7 6090.5 6132.6 6134.8 6161.3 6167.9 5742.5 5793.4 5806.9 5883.0 5893.7 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:66126.22 Median:35041.06 stddev:141426.62 (403.6%) stddev-sans-first:141737.45 12:41:33 INFO - PROCESS | 1644 | Values: 470.5 496.9 501.6 503.8 512.6 31306.2 40280.2 40338.6 40428.4 40469.8 41683.8 42104.2 42177.0 42188.8 42225.0 8331.9 8972.3 9165.2 9170.0 9199.4 8334.2 10962.7 11206.7 11230.3 11406.8 11152.3 11158.8 11207.7 11221.8 11225.3 8376.6 8601.7 11044.7 11105.6 11124.3 10720.3 11020.2 11022.4 11187.8 11243.9 39400.6 39713.2 39744.8 39887.4 39892.0 3976.3 3985.9 3999.8 4018.0 4027.7 28638.0 28685.7 28699.7 30378.4 30490.1 3069.8 3253.0 3358.7 3369.1 3369.9 430744.0 433688.0 434142.0 435340.0 437308.0 55986.0 60094.5 60130.4 60277.0 60329.4 61836.1 61908.7 61937.0 61967.4 61978.1 15957.8 16063.5 16144.0 16495.6 16498.0 11581.4 11818.3 11841.5 12792.3 12862.3 17813.1 21673.8 21787.5 22367.7 22452.4 8560.4 8722.6 8752.9 8799.8 8833.9 779.8 855.6 872.5 906.7 911.9 25272.4 29453.3 29898.0 29901.9 30674.5 1044.1 1047.5 1081.2 1083.2 1187.1 14914.6 15348.5 15456.9 15463.7 15737.5 609925.0 613994.0 614543.0 615150.0 616408.0 59232.7 59817.0 59867.4 59899.4 60106.1 1401.8 1443.0 1449.8 1453.4 1459.1 26998.1 28772.3 28969.3 29095.1 29567.7 49922.0 50262.2 50293.7 50355.7 50424.5 464983.0 539948.0 541038.0 541340.0 541686.0 25834.5 26284.6 26437.6 42642.0 43015.1 41207.2 41215.0 42732.0 42759.8 43156.0 34674.1 35040.0 35073.8 36058.0 36130.8 30737.0 34233.0 34604.8 34715.0 35751.5 34167.7 34850.8 34948.5 35004.9 35042.1 24889.0 27015.3 28882.6 29217.4 31617.8 22499.3 23348.6 23420.6 23451.8 23841.8 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:38169.52 Median:16831.01 stddev:107642.50 (639.5%) stddev-sans-first:107906.97 12:41:33 INFO - PROCESS | 1644 | Values: 334.5 367.8 386.4 399.1 425.2 14667.5 15085.2 17175.6 19986.4 20512.8 17204.8 20799.6 21347.4 21417.9 21551.1 5638.5 5811.2 6038.7 6049.6 6057.1 5795.5 5974.6 5986.7 5993.7 6012.1 3883.2 4082.6 7292.7 7679.5 7719.1 6417.6 7576.8 7667.0 7758.8 7769.9 7183.9 7590.8 7596.3 7600.4 7608.7 29263.6 29718.9 29863.9 29974.3 30609.8 2921.0 2935.6 2963.2 3052.2 3095.0 15914.5 16554.8 16569.6 17139.6 17686.7 1824.9 1878.9 1880.2 1883.4 1909.6 152719.0 154527.0 158185.0 159023.0 162359.2 18722.0 22767.7 23157.4 23273.5 23403.6 41006.8 41075.0 41945.6 42031.6 42194.6 7146.8 8537.4 9009.3 9078.3 9138.3 7134.0 8498.7 8819.9 8977.3 8992.0 4889.2 4891.5 4893.9 4907.3 4966.0 1875.4 1892.0 2058.4 2058.5 2068.8 962.4 992.6 1006.6 1023.4 1058.0 21161.9 21180.6 21305.1 21317.9 21723.2 1018.3 1030.3 1057.2 1059.4 1067.0 10515.7 10880.0 10905.5 10995.0 11048.0 412595.0 412679.0 734477.0 755565.0 776512.0 83286.6 96815.0 124251.0 139048.0 155010.0 932.3 947.7 970.3 971.4 972.7 18396.4 18770.5 18881.8 19356.6 19558.4 56497.4 57132.0 57571.7 57645.8 59328.7 82286.0 83292.0 84628.7 85969.0 86008.6 8063.4 8131.3 8197.9 8216.6 8487.8 11849.5 12508.2 12530.7 12711.4 12776.4 10792.7 10924.3 10951.2 11025.3 11086.7 9704.8 10102.5 10633.9 10670.4 11056.0 10594.7 10931.2 10958.2 11127.3 11248.9 7957.6 8065.9 8284.7 8600.0 8616.7 9237.0 9531.3 10092.8 11271.2 11601.0 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5441.12 Median:5102.50 stddev:3010.23 (59.0%) stddev-sans-first:3003.42 12:41:33 INFO - PROCESS | 1644 | Values: 1408.9 1413.2 1418.9 1423.8 1430.3 8222.6 8612.0 8627.8 8629.1 8639.8 7527.8 7809.3 7816.5 8063.4 8444.4 2281.4 2324.3 2325.1 2326.8 2333.0 2295.1 2329.6 2332.4 2344.9 2348.6 2041.8 2137.2 2279.9 2316.3 2321.7 2096.4 2107.6 2118.1 2248.3 2318.8 2343.2 2344.7 2349.3 2354.8 2355.6 8438.7 8520.3 8529.7 8540.0 8547.2 5074.6 5236.3 5238.3 5247.8 5258.9 2921.4 3346.4 3507.8 3555.6 3556.2 1131.6 1186.4 1226.8 1229.1 1281.1 11062.8 11170.0 11193.6 11197.8 11231.5 4497.3 4673.3 4730.4 4746.5 4747.5 9696.2 9701.8 9712.7 9714.9 9715.1 5278.8 5311.6 5311.8 5329.5 5332.7 4199.1 4517.0 4708.6 4805.9 4835.0 3810.9 3873.8 3874.1 4007.7 4016.9 1087.9 1219.8 1225.5 1232.8 1237.1 786.8 874.7 882.4 908.0 942.9 5527.7 5575.1 5634.9 5700.1 5862.5 958.7 958.8 960.2 971.2 1006.4 3399.6 3528.3 3695.5 3713.4 3741.6 8008.2 10261.2 10362.7 10376.7 10387.7 10062.1 10103.6 10313.0 10315.6 10316.8 7564.1 7692.8 7693.4 7697.7 7712.9 4525.9 5156.4 5163.5 5199.4 5353.4 9031.6 9080.6 9210.2 9327.8 9387.7 9148.5 9344.2 9459.2 9494.9 9510.0 6323.2 6556.1 6613.8 6832.1 6979.5 7584.0 7633.8 7998.4 8019.0 8112.4 7148.8 7268.0 7280.1 7311.6 7392.1 7025.2 7061.2 7065.5 7099.0 7151.0 6340.1 6349.7 6374.4 6444.9 6453.1 4901.7 4959.6 4972.0 4988.4 5027.9 4390.5 4864.6 4896.5 4917.5 5130.4 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4400.83 Median:4825.48 stddev:1311.31 (27.2%) stddev-sans-first:1278.69 12:41:33 INFO - PROCESS | 1644 | Values: 318.6 328.5 365.0 370.1 379.4 4825.6 5261.3 5286.7 5342.7 5389.0 4642.8 4977.8 5248.3 5279.0 5317.2 3138.6 3512.2 4194.2 4203.2 4206.3 3382.3 3563.4 4039.4 4131.3 4138.7 3287.3 4096.7 4103.9 4124.6 4134.1 3778.5 4132.7 4156.9 4158.7 4161.8 3099.5 3130.0 3687.2 3697.1 3715.6 4414.0 4750.2 4795.7 5066.9 5117.1 3829.8 4415.3 4564.6 4591.5 4614.4 4731.5 5155.3 5345.9 5583.6 5639.6 3174.5 3396.9 3769.4 3800.6 3853.8 5262.1 5491.4 5528.6 5696.6 5762.1 3699.0 4168.2 4173.3 4183.2 4186.6 3601.1 4233.9 4239.7 4271.3 4292.3 4088.3 4227.6 4617.9 4799.4 4827.3 4901.5 5144.7 5566.3 5588.7 5634.2 1251.2 1297.3 1305.9 1319.5 1375.8 936.5 939.4 979.2 994.2 1005.9 3851.7 4227.5 4359.6 4820.9 4883.8 4403.6 4824.1 5184.0 5358.7 5449.8 4179.5 4625.2 4820.9 4825.3 4875.7 2437.0 2681.6 2853.5 2854.8 2886.5 4858.8 5114.4 5660.9 5667.7 5681.4 5372.3 5382.8 5487.4 5491.4 5619.3 5608.8 5624.4 5630.9 5634.7 5656.4 5441.6 5457.5 5472.8 5479.8 5505.7 4187.9 4765.4 5304.5 5325.7 5345.8 5071.3 5080.8 5315.1 5447.6 5776.4 3666.3 3697.5 3750.3 3765.3 3925.8 4890.8 5631.4 5702.9 5715.6 5737.9 4822.6 5288.6 5520.3 5533.6 5562.4 4373.0 4981.6 5099.7 5101.6 5352.6 4786.4 4788.9 5234.2 5245.8 5361.4 5016.3 5083.2 5456.8 5512.4 5540.9 4917.9 5356.6 5437.9 5451.5 5458.6 12:41:33 INFO - PROCESS | 1644 | 12:41:33 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20835.24 Median:4923.80 stddev:30866.50 (626.9%) stddev-sans-first:30915.91 12:41:33 INFO - PROCESS | 1644 | Values: 659.8 716.3 757.9 805.4 808.9 14106.7 15724.0 15770.1 15773.9 15777.5 14214.1 14315.3 14462.3 14490.7 14499.7 849.0 861.6 873.8 877.2 880.3 862.5 875.8 877.4 877.6 877.8 927.1 929.9 930.2 930.9 931.1 922.4 923.4 924.7 926.6 927.7 684.7 685.1 687.7 688.0 689.8 14941.2 14997.8 15146.5 15543.9 15846.1 13925.4 14079.6 14131.2 14172.3 14209.4 11487.7 11598.3 11764.9 11907.7 12301.1 3503.5 3651.9 3673.4 3829.9 3838.4 108120.0 109228.0 110777.0 110837.8 114158.0 10610.9 10655.5 10676.5 10735.4 11078.6 22514.9 22553.8 22558.4 22568.4 22580.4 7515.7 7661.8 7665.6 7728.7 7796.1 4164.0 4206.3 4256.8 4324.1 4475.7 3479.2 3568.3 3572.5 3597.5 3599.3 596.5 603.2 603.3 604.9 605.3 630.5 632.6 659.4 671.4 685.9 9169.1 9325.3 9627.7 9916.6 10046.7 639.4 690.7 694.9 701.4 702.7 4613.9 4677.8 4899.6 4923.4 4924.2 98755.0 105498.0 105920.5 106136.0 106514.0 90932.5 95045.0 95913.0 96096.0 97525.0 81125.6 82441.0 83164.1 84814.2 86134.1 9589.5 9597.0 9618.8 9631.3 9717.8 51538.7 52147.0 52213.7 52515.5 53140.7 68972.0 69529.0 69770.0 70062.3 72248.0 11311.2 11431.3 11521.8 11525.2 12094.3 11998.3 12043.3 12244.2 12786.7 13270.0 11518.5 11983.5 12009.9 12218.1 12238.6 11809.9 11827.0 12064.8 12397.9 12625.0 11343.8 11564.7 11730.1 11919.6 12030.5 9718.7 9769.8 9941.3 10167.1 10506.8 7533.8 9652.9 9678.6 9705.6 9888.9 12:41:33 INFO - PROCESS | 1644 | -------- Summary: end -------- 12:41:33 INFO - PROCESS | 1644 | 12:41:34 INFO - TEST-INFO | 1644: exit 0 12:41:34 INFO - TEST-OK | dromaeo_css | took 1119441ms 12:41:34 INFO - TEST-START | kraken 12:41:34 INFO - operating with platform_type : mac_ 12:41:34 INFO - Initialising browser for kraken test... 12:41:34 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpBTRkfK/profile http://localhost:49247/getInfo.html) 12:41:36 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:41:36 INFO - PROCESS | 1655 | colorDepth:24 12:41:36 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:41:36 INFO - PROCESS | 1655 | __metrics 12:41:36 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:41:36 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:41:37 INFO - TEST-INFO | 1655: exit 0 12:41:37 INFO - Browser initialized. 12:41:37 INFO - Running cycle 1/1 for kraken test... 12:41:37 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpBTRkfK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:49 INFO - PROCESS | 1656 | RSS: Main: 212996096 12:41:49 INFO - PROCESS | 1656 | 12:41:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 12:41:53 INFO - PROCESS | 1656 | RSS: Main: 265433088 12:41:53 INFO - PROCESS | 1656 | 12:41:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 12:41:56 INFO - PROCESS | 1656 | RSS: Main: 274800640 12:41:56 INFO - PROCESS | 1656 | 12:41:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 12:42:00 INFO - PROCESS | 1656 | RSS: Main: 372473856 12:42:00 INFO - PROCESS | 1656 | 12:42:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 12:42:02 INFO - PROCESS | 1656 | RSS: Main: 276475904 12:42:02 INFO - PROCESS | 1656 | 12:42:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:05 INFO - PROCESS | 1656 | RSS: Main: 277983232 12:42:05 INFO - PROCESS | 1656 | 12:42:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:08 INFO - PROCESS | 1656 | RSS: Main: 318590976 12:42:08 INFO - PROCESS | 1656 | 12:42:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:12 INFO - PROCESS | 1656 | RSS: Main: 321044480 12:42:12 INFO - PROCESS | 1656 | 12:42:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 12:42:15 INFO - PROCESS | 1656 | RSS: Main: 314417152 12:42:15 INFO - PROCESS | 1656 | 12:42:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:18 INFO - PROCESS | 1656 | RSS: Main: 293937152 12:42:18 INFO - PROCESS | 1656 | 12:42:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:20 INFO - PROCESS | 1656 | RSS: Main: 297771008 12:42:20 INFO - PROCESS | 1656 | 12:42:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:42:23 INFO - PROCESS | 1656 | RSS: Main: 299253760 12:42:23 INFO - PROCESS | 1656 | 12:42:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:42:27 INFO - PROCESS | 1656 | RSS: Main: 315428864 12:42:27 INFO - PROCESS | 1656 | 12:42:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:42:30 INFO - PROCESS | 1656 | RSS: Main: 309186560 12:42:30 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 12:42:32 INFO - PROCESS | 1656 | RSS: Main: 353816576 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | __start_tp_report 12:42:32 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 12:42:32 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 12:42:32 INFO - PROCESS | 1656 | |i|pagename|runs| 12:42:32 INFO - PROCESS | 1656 | |0;ai-astar;114;112;111;111;112;111;111;113;112;112 12:42:32 INFO - PROCESS | 1656 | |1;audio-beat-detection;136;108;107;144;113;133;137;135;126;110 12:42:32 INFO - PROCESS | 1656 | |2;audio-dft;184;183;183;184;185;181;183;184;184;183 12:42:32 INFO - PROCESS | 1656 | |3;audio-fft;82;59;59;88;60;60;60;57;75;58 12:42:32 INFO - PROCESS | 1656 | |4;audio-oscillator;88;71;88;69;67;69;79;92;69;82 12:42:32 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;86;89;86;89;85;89;87;89;84 12:42:32 INFO - PROCESS | 1656 | |6;imaging-darkroom;94;94;95;95;95;95;95;95;95;95 12:42:32 INFO - PROCESS | 1656 | |7;imaging-desaturate;88;87;88;88;85;88;85;131;139;86 12:42:32 INFO - PROCESS | 1656 | |8;json-parse-financial;56;52;52;53;56;57;56;56;56;56 12:42:32 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;67;81;72;73;71;69;69;69;68;71 12:42:32 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;84;84;84;87;87;82;83;83;84;83 12:42:32 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;139;136;147;143;131;139;143;145;196;178 12:42:32 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;169;164;168;168;167;169;165;166;165;213 12:42:32 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;66;65;65;65;66;65;64;64;65 12:42:32 INFO - PROCESS | 1656 | __end_tp_report 12:42:32 INFO - PROCESS | 1656 | __start_cc_report 12:42:32 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2095 12:42:32 INFO - PROCESS | 1656 | __end_cc_report 12:42:32 INFO - PROCESS | 1656 | __startTimestamp1487882552621__endTimestamp 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | ------- Summary: start ------- 12:42:32 INFO - PROCESS | 1656 | Number of tests: 14 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:0.99 (0.9%) stddev-sans-first:0.71 12:42:32 INFO - PROCESS | 1656 | Values: 114.0 112.0 111.0 111.0 112.0 111.0 111.0 113.0 112.0 112.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:134.00 stddev:14.04 (10.5%) stddev-sans-first:14.30 12:42:32 INFO - PROCESS | 1656 | Values: 136.0 108.0 107.0 144.0 113.0 133.0 137.0 135.0 126.0 110.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.07 (0.6%) stddev-sans-first:1.12 12:42:32 INFO - PROCESS | 1656 | Values: 184.0 183.0 183.0 184.0 185.0 181.0 183.0 184.0 184.0 183.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.00 stddev:11.41 (19.0%) stddev-sans-first:10.49 12:42:32 INFO - PROCESS | 1656 | Values: 82.0 59.0 59.0 88.0 60.0 60.0 60.0 57.0 75.0 58.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:80.50 stddev:9.56 (11.9%) stddev-sans-first:9.34 12:42:32 INFO - PROCESS | 1656 | Values: 88.0 71.0 88.0 69.0 67.0 69.0 79.0 92.0 69.0 82.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.50 stddev:1.87 (2.1%) stddev-sans-first:1.96 12:42:32 INFO - PROCESS | 1656 | Values: 88.0 86.0 89.0 86.0 89.0 85.0 89.0 87.0 89.0 84.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 12:42:32 INFO - PROCESS | 1656 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:87.50 stddev:20.41 (23.3%) stddev-sans-first:21.42 12:42:32 INFO - PROCESS | 1656 | Values: 88.0 87.0 88.0 88.0 85.0 88.0 85.0 131.0 139.0 86.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:56.00 stddev:1.89 (3.4%) stddev-sans-first:1.96 12:42:32 INFO - PROCESS | 1656 | Values: 56.0 52.0 52.0 53.0 56.0 57.0 56.0 56.0 56.0 56.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.97 (5.6%) stddev-sans-first:3.94 12:42:32 INFO - PROCESS | 1656 | Values: 67.0 81.0 72.0 73.0 71.0 69.0 69.0 69.0 68.0 71.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:1.66 (2.0%) stddev-sans-first:1.76 12:42:32 INFO - PROCESS | 1656 | Values: 84.0 84.0 84.0 87.0 87.0 82.0 83.0 83.0 84.0 83.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:144.00 stddev:20.63 (14.3%) stddev-sans-first:21.51 12:42:32 INFO - PROCESS | 1656 | Values: 139.0 136.0 147.0 143.0 131.0 139.0 143.0 145.0 196.0 178.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:168.00 stddev:14.72 (8.8%) stddev-sans-first:15.59 12:42:32 INFO - PROCESS | 1656 | Values: 169.0 164.0 168.0 168.0 167.0 169.0 165.0 166.0 165.0 213.0 12:42:32 INFO - PROCESS | 1656 | 12:42:32 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:1.16 (1.8%) stddev-sans-first:0.71 12:42:32 INFO - PROCESS | 1656 | Values: 68.0 66.0 65.0 65.0 65.0 66.0 65.0 64.0 64.0 65.0 12:42:32 INFO - PROCESS | 1656 | -------- Summary: end -------- 12:42:32 INFO - PROCESS | 1656 | 12:42:33 INFO - TEST-INFO | 1656: exit 0 12:42:33 INFO - TEST-OK | kraken | took 59271ms 12:42:33 INFO - SUITE-END | took 1178s 12:42:33 INFO - Completed test suite (00:19:38) 12:42:33 INFO - javascript benchmark 12:42:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.6562660920604, 3276.1154488288157, 3333.100062982996, 3340.7661463697536, 3386.0349800306917, 5614.185943038257, 5671.914921276184, 5680.176374425706, 5681.147827825828, 5715.942550628126, 5641.605087643867, 5712.771489140429, 5726.541876649869, 5728.341240757314, 5779.537636989041, 4857.611391088704, 5166.354205724294, 5196.662216955905, 5232.293640358541, 5259.526642602165, 5078.466760990098, 5128.8974220515565, 5131.153359695646, 5282.709450980195, 5298.36419629645, 4880.341153944208, 5126.71803050832, 5175.146100893367, 5180.922286165711, 5268.841934741964, 4754.358161648185, 5241.8427447176455, 5271.578273738091, 5271.868203294948, 5302.840914772563, 5021.171506701427, 5120.948790512121, 5177.301064356301, 5206.323177986876, 5213.2701421800975, 5367.114426119686, 5478.342598888119, 5483.0, 5521.558275338003, 5588.273524441837, 6002.519798416116, 6072.028475443908, 6075.0, 6098.0, 6112.419320164578, 6087.939120608826, 6159.938400615982, 6161.938380616226, 6165.876682466325, 6171.290301615327, 5561.193626924073, 5645.915311270334, 5659.547236221092, 5722.914156287659, 5733.168690539848, 6190.0405437157315, 6248.156498872685, 6267.71795269214, 6269.404406581368, 6276.403741644536, 6053.939460605426, 6148.077788331786, 6151.108089327023, 6170.228721409824, 6184.5052395808225, 6116.26604807426, 6221.129041934133, 6222.564420490522, 6296.464800492009, 6306.684665766693, 6102.511799056065, 6103.664298463538, 6139.627825652554, 6148.1085242639565, 6155.138280640714, 6247.468965137923, 6248.781292654735, 6249.1563638908165, 6268.780592679234, 6277.466415354655, 4908.993656300449, 4917.459079501252, 4953.777080031407, 4969.229769385751, 4993.0, 4340.674449416307, 4377.190219809346, 4383.276759334674, 4446.866594002185, 4449.666275029385, 5676.8864622707315, 5687.630304030225, 5694.515966142924, 5696.829095127153, 5700.458456446631, 5862.501687356537, 5974.253218347707, 6202.193714817045, 6206.379362063757, 6244.875922333933, 5720.227769251181, 5753.194552762617, 5762.394948530344, 5784.0, 5800.9129863052085, 5451.0, 5451.236826844166, 5451.536619387396, 5465.699386533699, 5467.398586155519, 6192.845178870564, 6220.688965551613, 6222.533310001768, 6226.221722284715, 6269.0, 6294.590851594632, 6299.937000629935, 6307.30619631831, 6312.873742525032, 6326.493880489642, 6223.3465486123305, 6283.340249273943, 6289.591176573691, 6290.842728931813, 6297.55917085818, 5098.260752190838, 6006.249218847644, 6106.114613380946, 6206.813795586139, 6207.224096987877, 6098.634081955097, 6163.0, 6209.875802484015, 6253.5622506424115, 6313.1477250572425, 6116.29662588808, 6119.204503414706, 6124.663143527148, 6130.724117414817, 6135.815925522242, 5918.674472904032, 6023.45788879003, 6033.396660333931, 6084.087386892002, 6111.96944015277, 5798.913016304843, 5888.882222355443, 5925.377835327228, 5943.019401798655, 6017.729202185825, 6090.543209259235, 6132.63204207749, 6134.8159555213415, 6161.291451483136, 6167.876642467215, 5742.483176514133, 5793.420657934173, 5806.941930580809, 5882.970585147047, 5893.675847828411], "value": 5661.571819785011, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.4519235091119, 496.9105560999021, 501.58869726823997, 503.8060346766494, 512.5873671694286, 31306.217344566398, 40280.1943961121, 40338.59661403385, 40428.39357409638, 40469.78590642278, 41683.791581042125, 42104.15791684164, 42177.0, 42188.78905605468, 42225.0, 8331.916680833205, 8972.327075469333, 9165.175134237918, 9170.0, 9199.448033118, 8334.249917507423, 10962.671119866376, 11206.719832004224, 11230.270032447906, 11406.828897566544, 11152.275102118378, 11158.832617510743, 11207.66377008687, 11221.831672524919, 11225.32648041116, 8376.623051962677, 8601.741947741577, 11044.668659940215, 11105.55577776888, 11124.332540047622, 10720.303180293256, 11020.173486988508, 11022.44887755609, 11187.832182517268, 11243.943780281128, 39400.60599394027, 39713.20573588541, 39744.80127599373, 39887.4016889744, 39892.0, 3976.284268831609, 3985.920281594381, 3999.8000099994883, 4018.0, 4027.6979226558124, 28638.0, 28685.71314286872, 28699.713002869914, 30378.392432151235, 30490.085297441114, 3069.8465076746074, 3252.9512057319157, 3358.6809253120914, 3369.0903456066726, 3369.882054128094, 430744.0, 433688.0, 434142.0, 435340.0, 437308.0, 55986.0, 60094.49763755941, 60130.398696013355, 60277.0, 60329.39670603326, 61836.07245891315, 61908.69045654833, 61937.0, 61967.38032619706, 61978.070328945105, 15957.760633590506, 16063.518094457186, 16143.950643208154, 16495.587610309838, 16498.0, 11581.42092895352, 11818.290902545874, 11841.526338946345, 12792.2964236966, 12862.292573908524, 17813.05435524221, 21673.783262167177, 21787.455313617287, 22367.66448503274, 22452.438689032904, 8560.357973152039, 8722.563871806384, 8752.8687069694, 8799.824003519894, 8833.867491987625, 779.8302546180729, 855.5935930432995, 872.4721543466238, 906.6645341223658, 911.9498427586547, 25272.36819079501, 29453.26366840803, 29897.953571625323, 29901.850490747405, 30674.53988190179, 1044.1177205261542, 1047.450088703437, 1081.2485322700804, 1083.160550573312, 1187.0681515010847, 14914.627134321729, 15348.539543813704, 15456.918015739015, 15463.690726185414, 15737.527874163794, 609925.0, 613994.0, 614543.0, 615150.0, 616408.0, 59232.70383648054, 59817.0, 59867.401325987055, 59899.40100599025, 60106.09840852391, 1401.8107555480083, 1442.985570144285, 1449.7825326201155, 1453.4476898778184, 1459.109942934809, 26998.140568995983, 28772.280692982844, 28969.275768105967, 29095.12714618565, 29567.704322957357, 49922.00155996932, 50262.246066308304, 50293.74853125711, 50355.74822125866, 50424.49575504198, 464983.0, 539948.0, 541038.0, 541340.0, 541686.0, 25834.483310334064, 26284.605730914434, 26437.60343594809, 42642.0, 43015.139697206505, 41207.1758564833, 41214.96962575959, 42732.0, 42759.7862010688, 43156.0, 34674.066569176866, 35040.0, 35073.82463087668, 36058.0, 36130.8193459031, 30737.0, 34233.0, 34604.82697586496, 34714.9585512435, 35751.46372804462, 34167.65832341645, 34850.82574587111, 34948.47577286394, 35004.9498515045, 35042.123946901535, 24889.0, 27015.324616884736, 28882.566761498176, 29217.41565168727, 31617.84191079122, 22499.338812456932, 23348.59908405501, 23420.648690269318, 23451.76548234496, 23841.761582384646], "value": 19883.533455616696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.4565081742169, 367.7977112588079, 386.4300157268028, 399.1318881432883, 425.1900130251871, 14667.486637967673, 15085.170315632637, 17175.57061073472, 19986.401087912967, 20512.794872051276, 17204.82795172048, 20799.584008319824, 21347.35957921261, 21417.892910535465, 21551.13795448184, 5638.492535671789, 5811.215485909401, 6038.667873266991, 6049.606775559574, 6057.121717350981, 5795.507381872546, 5974.641521508702, 5986.670733109699, 5993.670348130851, 6012.128241404994, 3883.2427676603074, 4082.6121518455698, 7292.7447539336135, 7679.462437629368, 7719.07371115467, 6417.582857114273, 7576.772696819076, 7667.003289572375, 7758.806029849271, 7769.873368361556, 7183.89224161638, 7590.8481830363635, 7596.278353556403, 7600.429967752385, 7608.733694320672, 29263.561046584317, 29718.851405743048, 29863.85068074646, 29974.250643733863, 30609.846950765103, 2921.0, 2935.6036935013713, 2963.2147480917574, 3052.1759125036115, 3094.9941269087412, 15914.52256432309, 16554.83445165545, 16569.591584715312, 17139.57151071221, 17686.734698979526, 1824.9087545622665, 1878.8872667639985, 1880.2356508986081, 1883.4067268810281, 1909.598984213317, 152719.0, 154527.0, 158185.0, 159022.99999999884, 162359.1882040594, 18722.0, 22767.658485122738, 23157.42106447352, 23273.53452930932, 23403.648945265835, 41006.79496602498, 41074.9731256721, 41945.58054419478, 42031.57968420276, 42194.57805421968, 7146.821329466701, 8537.402381833337, 9009.279257659502, 9078.273738100936, 9138.314626403046, 7134.0, 8498.66005359791, 8819.91180088191, 8977.32670049749, 8992.0, 4889.217725163957, 4891.461939186686, 4893.853184404474, 4907.312976183266, 4965.950340496549, 1875.37175046361, 1892.0417368024305, 2058.3619078085544, 2058.5265388960624, 2068.7931206879493, 962.4465932089076, 992.6178421307849, 1006.5772375602264, 1023.3677284730842, 1057.962971296001, 21161.89419052895, 21180.576388472145, 21305.14779408806, 21317.893410532848, 21723.23968661121, 1018.277023313441, 1030.299396410448, 1057.2176589324, 1059.3855563773147, 1066.9573217071231, 10515.737106572244, 10880.02869983751, 10905.509252083675, 10994.95547922946, 11048.0, 412595.0, 412679.0, 734477.0, 755565.0, 776512.0, 83286.58356708298, 96815.0, 124251.0, 139048.0, 155010.0, 932.2774849491698, 947.6920000999703, 970.2674480766975, 971.3977334052933, 972.7276362618478, 18396.448106556825, 18770.49836013143, 18881.811181888006, 19356.61286774285, 19558.413247602595, 56497.43502564922, 57132.0, 57571.71214143903, 57645.84708305894, 59328.70335648294, 82286.0, 83292.0, 84628.73056904029, 85969.0, 86008.56995715231, 8063.395245356739, 8131.2681858633005, 8197.877031844646, 8216.589170541329, 8487.787805304917, 11849.466773995364, 12508.186967847061, 12530.74938501243, 12711.364431778558, 12776.425060872098, 10792.730181745204, 10924.2899211551, 10951.233413661286, 11025.338479691245, 11086.722831929266, 9704.75738106553, 10102.545385457524, 10633.8936610636, 10670.413127277763, 11056.0, 10594.735131621772, 10931.234813562975, 10958.232923695265, 11127.332360058423, 11248.850321909878, 7957.602119893866, 8065.87901181471, 8284.668613255644, 8600.0, 8616.698415555426, 9237.0, 9531.332806703464, 10092.798144037224, 11271.211015228855, 11601.01391381742], "value": 10616.753192924802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.9225092619909, 1413.2227274998747, 1418.8652078052585, 1423.7579611466047, 1430.3491911180413, 8222.588870556478, 8612.0, 8627.784305392368, 8629.137086291375, 8639.827203455934, 7527.811804704871, 7809.336206422447, 7816.491928024674, 8063.435559510836, 8444.366672499556, 2281.3954302109955, 2324.2562380038394, 2325.104834638668, 2326.7789559991775, 2333.0, 2295.0819672131115, 2329.615613423783, 2332.3819187915215, 2344.8593084414906, 2348.6477028445784, 2041.7856125106873, 2137.1985505435464, 2279.943001424969, 2316.293530473205, 2321.7446080931086, 2096.413004358782, 2107.6417009108413, 2118.142152428269, 2248.325502349289, 2318.802901753353, 2343.203310874311, 2344.730356009047, 2349.271725765001, 2354.81161507079, 2355.599548076814, 8438.662453501853, 8520.275776558938, 8529.744107676717, 8539.957300213522, 8547.188017138362, 5074.568661663763, 5236.319278493809, 5238.345206849144, 5247.763850626731, 5258.92111618326, 2921.4011127718977, 3346.380919529895, 3507.8070706111153, 3555.591107022674, 3556.1643013891717, 1131.5586921100778, 1186.4483015397882, 1226.7730469863016, 1229.1457437081233, 1281.0904257976847, 11062.834057489144, 11170.0, 11193.608223712132, 11197.776044479066, 11231.494582743715, 4497.325401189848, 4673.322368256584, 4730.432348118248, 4746.501617330131, 4747.454042785055, 9696.175825054808, 9701.757456063513, 9712.660056897974, 9714.854277185848, 9715.125638692552, 5278.788848446096, 5311.628186027018, 5311.814086506954, 5329.546988505943, 5332.706701131475, 4199.118185181146, 4516.9774151129695, 4708.646851486153, 4805.927911081337, 4835.032993401333, 3810.8856734298015, 3873.786941718176, 3874.0629685157423, 4007.719459637856, 4016.9397459038137, 1087.9129669626411, 1219.7865373559769, 1225.4669218889812, 1232.7534493101416, 1237.10928131745, 786.8072322281078, 874.6545114679666, 882.4308321132834, 908.0, 942.9104235097655, 5527.668339899619, 5575.135853942646, 5634.887302253932, 5700.116481945305, 5862.472377486047, 958.6884262614678, 958.8274110660009, 960.1790469148917, 971.2375785008876, 1006.4464544500642, 3399.5920489541413, 3528.347255757693, 3695.4826324314618, 3713.442983552489, 3741.644543768392, 8008.199180081945, 10261.179105671528, 10362.740931476623, 10376.688699339033, 10387.740306492247, 10062.094411502854, 10103.646372376932, 10313.0, 10315.587376504856, 10316.845247321296, 7564.092308922965, 7692.807679807937, 7693.422993275415, 7697.653605587762, 7712.92287077122, 4525.932111018403, 5156.432792392909, 5163.528394407575, 5199.4020687621405, 5353.357597088374, 9031.63873445055, 9080.591373388346, 9210.217131543626, 9327.766805829908, 9387.671431499864, 9148.451092934445, 9344.15902568772, 9459.195968342769, 9494.85757713621, 9510.0, 6323.177986861679, 6556.082148499309, 6613.834654133685, 6832.111825462658, 6979.546329488567, 7584.0, 7633.847323053396, 7998.360131189374, 8019.03771547418, 8112.391570632344, 7148.785536433916, 7268.0, 7280.053593033083, 7311.561306321638, 7392.112946446461, 7025.22503611081, 7061.152661680426, 7065.505414620927, 7099.0, 7150.998860159478, 6340.1440805490665, 6349.68251587428, 6374.426301632875, 6444.903326450013, 6453.128827608401, 4901.705897646153, 4959.57843583285, 4971.9502804972935, 4988.376452943382, 5027.918997415573, 4390.538993405647, 4864.562189402971, 4896.485868983706, 4917.459079501324, 5130.435652078343], "value": 4407.437236444437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.56833989943624, 328.4527107241788, 364.99452508212363, 370.09142555027404, 379.40622925122165, 4825.638077144211, 5261.316028916241, 5286.709230992294, 5342.706151161679, 5389.003034438631, 4642.837500687477, 4977.83020990068, 5248.291480650111, 5278.970600732859, 5317.218731724867, 3138.6259770746424, 3512.2273099918107, 4194.203101410738, 4203.180379825935, 4206.284931561642, 3382.30662714143, 3563.3942229820864, 4039.434479172918, 4131.277026520362, 4138.730982486129, 3287.342531493699, 4096.733712308705, 4103.9384409233735, 4124.587541245882, 4134.0698342872765, 3778.452124441968, 4132.690048246363, 4156.875293741193, 4158.667306615479, 4161.83352665893, 3099.5040793473163, 3129.9687003129907, 3687.1519550503267, 3697.077519689773, 3715.64701353371, 4413.955860441387, 4750.168720473903, 4795.6882802618065, 5066.949330506684, 5117.130087885032, 3829.75532951789, 4415.337699345092, 4564.612007979293, 4591.51789062147, 4614.423197100363, 4731.503192164808, 5155.329807125087, 5345.946540534623, 5583.553315734732, 5639.6334238274385, 3174.507951267557, 3396.915077123067, 3769.4345848122725, 3800.619938006205, 3853.7880416577077, 5262.10544207478, 5491.395946445888, 5528.5577153828, 5696.572757043238, 5762.135679648044, 3698.9815050925113, 4168.18720349535, 4173.269677806372, 4183.163367326486, 4186.623203911662, 3601.1357274254515, 4233.872983810491, 4239.703220774516, 4271.295236285978, 4292.313229883203, 4088.2845502037026, 4227.598378154071, 4617.930731039037, 4799.36860929198, 4827.275908613736, 4901.460839307673, 5144.6655967362, 5566.276384070126, 5588.720563971785, 5634.1830434586755, 1251.1992324912064, 1297.2864924291603, 1305.8694130587055, 1319.511780641169, 1375.7729974554086, 936.5244536940783, 939.3518472254123, 979.2264111352094, 994.1996692662332, 1005.8943810899897, 3851.730378873452, 4227.450431443954, 4359.629431498358, 4820.927686084711, 4883.7778810641075, 4403.559644035635, 4824.131656301829, 5183.974080129575, 5358.660334916271, 5449.782008719607, 4179.519355274121, 4625.236835922103, 4820.903581928412, 4825.324454576362, 4875.68308059975, 2436.9634455483183, 2681.5843544250674, 2853.4721076600895, 2854.84298363592, 2886.466003789306, 4858.82994095205, 5114.442778610694, 5660.915086273709, 5667.676252736441, 5681.431856814285, 5372.260147324892, 5382.811601593926, 5487.42382049887, 5491.368492623399, 5619.269494965628, 5608.775648973995, 5624.353199382042, 5630.930123276647, 5634.661920284796, 5656.37779844225, 5441.646292990943, 5457.508824205682, 5472.835814925558, 5479.780808767764, 5505.697186654773, 4187.890773256622, 4765.356676848581, 5304.469553044664, 5325.721826761472, 5345.786168553214, 5071.340725705634, 5080.771365288645, 5315.123004704181, 5447.645903016291, 5776.42235776419, 3666.3400587894434, 3697.48235247071, 3750.306193354238, 3765.2657731743143, 3925.823337949742, 4890.755462226945, 5631.352394474688, 5702.914456283076, 5715.599908006399, 5737.885242295048, 4822.638302127284, 5288.629795914249, 5520.343896830855, 5533.5849811263515, 5562.3881373048125, 4372.978135109304, 4981.6014718823135, 5099.69401835891, 5101.642884998015, 5352.598555108305, 4786.425628924551, 4788.922492439173, 5234.168041185555, 5245.763940622757, 5361.4370491099435, 5016.322796422436, 5083.212102124176, 5456.7544460498575, 5512.4211957745465, 5540.889182216414, 4917.85246442593, 5356.589534709475, 5437.864053398696, 5451.519558600748, 5458.61789674719], "value": 4006.589108038221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.7728225500571, 716.3409663109938, 757.9242075792422, 805.3959530352236, 808.8988876390451, 14106.71786564268, 15724.03449568804, 15770.132642704646, 15773.921130394347, 15777.526674199793, 14214.147151170937, 14315.284235788195, 14462.276886155676, 14490.710185796277, 14499.710005799878, 848.9617967191491, 861.6338056326052, 873.8077622922979, 877.2236570634971, 880.3001613717079, 862.5213006781233, 875.8248350329927, 877.4209022045451, 877.5656050255133, 877.7717793373737, 927.1331305229631, 929.8512238041915, 930.1675000874213, 930.8929473110611, 931.12009151352, 922.4373132389237, 923.3813345058827, 924.7272054743867, 926.5691453474133, 927.6984979881531, 684.650828077679, 685.0580451878667, 687.669918439147, 688.0, 689.7965100295424, 14941.17823519706, 14997.775033374508, 15146.545603631908, 15543.922280388639, 15846.128462934534, 13925.373358198905, 14079.648008799759, 14131.152130872078, 14172.2608932597, 14209.36057877398, 11487.655370338904, 11598.304101753922, 11764.882351176464, 11907.702307442296, 12301.138920275584, 3503.4919936609303, 3651.8539258429632, 3673.430618254175, 3829.942550861739, 3838.366669499529, 108119.99999999921, 109228.0, 110777.0, 110837.78324433466, 114158.0, 10610.946945265225, 10655.520501577448, 10676.466176691134, 10735.409552474688, 11078.612248571422, 22514.887425562767, 22553.77446225517, 22558.436039098826, 22568.435789105402, 22580.435489112577, 7515.736949206752, 7661.846763064707, 7665.578393188428, 7728.690852365955, 7796.142023432935, 4163.958360416357, 4206.26390381682, 4256.787160641956, 4324.0919406924895, 4475.686701930898, 3479.1823921378455, 3568.286342731464, 3572.5355703758346, 3597.478365636968, 3599.2981368632863, 596.4661627843143, 603.1917230910601, 603.2790814976062, 604.9274087109575, 605.3159929279885, 630.5145038320468, 632.585656395062, 659.3966520633567, 671.3554987212233, 685.8902575587982, 9169.128932751379, 9325.300602454843, 9627.652128701988, 9916.603335866628, 10046.69859904204, 639.4228393536555, 690.6546726636682, 694.8947016753654, 701.3547536266545, 702.6592102830124, 4613.930791038137, 4677.78377621814, 4899.608031357483, 4923.384576927884, 4924.212126059813, 98755.0, 105498.0, 105920.47039764906, 106136.0, 106514.0, 90932.54533727422, 95045.0, 95913.0408695918, 96096.0, 97525.04949900962, 81125.59437202776, 82441.0, 83164.07751001831, 84814.15185848186, 86134.13865861262, 9589.472579008221, 9596.95201523988, 9618.807623847624, 9631.277654175825, 9717.805643887225, 51538.74230628823, 52147.0, 52213.7389313051, 52515.47484525259, 53140.73429632827, 68972.0, 69529.0, 69769.9534506993, 70062.29937700558, 72248.0, 11311.208215424842, 11431.314121152758, 11521.827172592586, 11525.193236473366, 12094.334811585448, 11998.340091295062, 12043.277403355827, 12244.204126731735, 12786.744265114832, 13270.004749643622, 11518.481668324777, 11983.460744266355, 12009.939950300193, 12218.083643726934, 12238.571649992564, 11809.940950295193, 11827.0, 12064.82862516035, 12397.93801030989, 12625.0, 11343.792430204086, 11564.710882228012, 11730.061595072202, 11919.582814601446, 12030.458629361525, 9718.70843874685, 9769.804603908024, 9941.25440591944, 10167.056581750168, 10506.789864202521, 7533.80777616089, 9652.855207171757, 9678.564464598969, 9705.563249653924, 9888.901110988798], "value": 7392.013563266864, "unit": "score"}], "name": "dromaeo_css", "value": 7337.238983873748, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 111.0, 111.0, 112.0, 111.0, 111.0, 113.0, 112.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 108.0, 107.0, 144.0, 113.0, 133.0, 137.0, 135.0, 126.0, 110.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 184.0, 185.0, 181.0, 183.0, 184.0, 184.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 88.0, 60.0, 60.0, 60.0, 57.0, 75.0, 58.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 88.0, 69.0, 67.0, 69.0, 79.0, 92.0, 69.0, 82.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 89.0, 85.0, 89.0, 87.0, 89.0, 84.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 88.0, 85.0, 88.0, 85.0, 131.0, 139.0, 86.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 52.0, 53.0, 56.0, 57.0, 56.0, 56.0, 56.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 81.0, 72.0, 73.0, 71.0, 69.0, 69.0, 69.0, 68.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 87.0, 87.0, 82.0, 83.0, 83.0, 84.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 147.0, 143.0, 131.0, 139.0, 143.0, 145.0, 196.0, 178.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 164.0, 168.0, 168.0, 167.0, 169.0, 165.0, 166.0, 165.0, 213.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 64.0, 64.0, 65.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1438.4, "alertThreshold": 2.0}]} 12:42:33 INFO - Return code: 0 12:42:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:42:33 ERROR - # TBPL SUCCESS # 12:42:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:33 INFO - 2017-02-23 12:42:33.962 screenresolution[1660:9837] starting screenresolution argv=screenresolution get 12:42:33 INFO - 2017-02-23 12:42:33.965 screenresolution[1660:9837] Display 0: 1600x1200x32@60 12:42:33 INFO - 2017-02-23 12:42:33.972 screenresolution[1661:9840] starting screenresolution argv=screenresolution list 12:42:33 INFO - Available Modes on Display 0 12:42:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:34 INFO - Intel Iris: 12:42:34 INFO - Chipset Model: Intel Iris 12:42:34 INFO - Type: GPU 12:42:34 INFO - Bus: Built-In 12:42:34 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:34 INFO - Vendor: Intel (0x8086) 12:42:34 INFO - Device ID: 0x0a2e 12:42:34 INFO - Revision ID: 0x0009 12:42:34 INFO - Displays: 12:42:34 INFO - Display: 12:42:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:34 INFO - Display Serial Number: 1600x1200 60 12:42:34 INFO - Main Display: Yes 12:42:34 INFO - Mirror: Off 12:42:34 INFO - Online: Yes 12:42:34 INFO - Rotation: Supported 12:42:34 INFO - Return code: 0 12:42:34 INFO - Running post-action listener: _resource_record_post_action 12:42:34 INFO - [mozharness: 2017-02-23 20:42:34.217283Z] Finished run-tests step (success) 12:42:34 INFO - Running post-run listener: _resource_record_post_run 12:42:34 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38642688; Write bytes: 506673152; Read time: 374; Write time: 1531 12:42:34 INFO - TinderboxPrint: CPU usage
53.7% 12:42:34 INFO - TinderboxPrint: I/O read bytes / time
38,642,688 / 374 12:42:34 INFO - TinderboxPrint: I/O write bytes / time
506,673,152 / 1,531 12:42:34 INFO - TinderboxPrint: CPU idle
2,221.0 (46.3%) 12:42:34 INFO - TinderboxPrint: CPU system
963.0 (20.1%) 12:42:34 INFO - TinderboxPrint: CPU user
1,617.6 (33.7%) 12:42:34 INFO - TinderboxPrint: Swap in / out
246,599,680 / 0 12:42:34 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201421312; Write bytes: 198995968; Read time: 15862; Write time: 366 12:42:34 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37721088; Write bytes: 297850880; Read time: 361; Write time: 1133 12:42:34 INFO - Running post-run listener: _upload_blobber_files 12:42:34 INFO - Blob upload gear active. 12:42:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:42:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:42:34 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:42:34 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:42:35 INFO - (blobuploader) - INFO - Open directory for files ... 12:42:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:42:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:36 INFO - (blobuploader) - INFO - Done attempting. 12:42:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:42:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:42:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:37 INFO - (blobuploader) - INFO - Done attempting. 12:42:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:42:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:38 INFO - (blobuploader) - INFO - Done attempting. 12:42:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:42:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:42:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:40 INFO - (blobuploader) - INFO - Done attempting. 12:42:40 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:40 INFO - Return code: 0 12:42:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c26c336361aa2becc0fda016adbe215ff58d730f292215322c50fb620aad4f602f4d8e362b1f94035dc49c0eb585faca50f3b742f90f1b6bb6475a1ff8dab6f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/71a2d69522a2cdfd6e0d1f59f71e2f8aef52ac78fa4ce87a4d13311e9c94e8ae8034aeddf880f210899cc8c2fadb562d057fe3f6e13474957a9d1af95c1595ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7091b98e87cee2600554207a331e954c8d553815aa2a70208545f5041249b2beb14d8b1cba59466d1dd4afc8012439985114b7c487a55b681b50d91a4d1ef934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f80eb712057aed70a3f87ccce0059b7ac0dc876e75622888ed9a2aa8fec2b3a79c3f23017f9462ca3dd6de94faa853eca35fdbef330331a5f087302ca631642"} 12:42:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:40 INFO - Contents: 12:42:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c26c336361aa2becc0fda016adbe215ff58d730f292215322c50fb620aad4f602f4d8e362b1f94035dc49c0eb585faca50f3b742f90f1b6bb6475a1ff8dab6f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/71a2d69522a2cdfd6e0d1f59f71e2f8aef52ac78fa4ce87a4d13311e9c94e8ae8034aeddf880f210899cc8c2fadb562d057fe3f6e13474957a9d1af95c1595ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7091b98e87cee2600554207a331e954c8d553815aa2a70208545f5041249b2beb14d8b1cba59466d1dd4afc8012439985114b7c487a55b681b50d91a4d1ef934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f80eb712057aed70a3f87ccce0059b7ac0dc876e75622888ed9a2aa8fec2b3a79c3f23017f9462ca3dd6de94faa853eca35fdbef330331a5f087302ca631642"} 12:42:40 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:40 INFO - Copying logs to upload dir... 12:42:40 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.473926 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-23 12:42:40.500308) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 12:42:40.504017) ========= 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.NnVSttjAev/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.flJYnFQXYF/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-Aurora/sha512/c26c336361aa2becc0fda016adbe215ff58d730f292215322c50fb620aad4f602f4d8e362b1f94035dc49c0eb585faca50f3b742f90f1b6bb6475a1ff8dab6f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/71a2d69522a2cdfd6e0d1f59f71e2f8aef52ac78fa4ce87a4d13311e9c94e8ae8034aeddf880f210899cc8c2fadb562d057fe3f6e13474957a9d1af95c1595ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7091b98e87cee2600554207a331e954c8d553815aa2a70208545f5041249b2beb14d8b1cba59466d1dd4afc8012439985114b7c487a55b681b50d91a4d1ef934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f80eb712057aed70a3f87ccce0059b7ac0dc876e75622888ed9a2aa8fec2b3a79c3f23017f9462ca3dd6de94faa853eca35fdbef330331a5f087302ca631642"} build_url:https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011080 build_url: 'https://queue.taskcluster.net/v1/task/GecRTZljQQe9PSOZmZZUNA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c26c336361aa2becc0fda016adbe215ff58d730f292215322c50fb620aad4f602f4d8e362b1f94035dc49c0eb585faca50f3b742f90f1b6bb6475a1ff8dab6f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/71a2d69522a2cdfd6e0d1f59f71e2f8aef52ac78fa4ce87a4d13311e9c94e8ae8034aeddf880f210899cc8c2fadb562d057fe3f6e13474957a9d1af95c1595ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7091b98e87cee2600554207a331e954c8d553815aa2a70208545f5041249b2beb14d8b1cba59466d1dd4afc8012439985114b7c487a55b681b50d91a4d1ef934", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f80eb712057aed70a3f87ccce0059b7ac0dc876e75622888ed9a2aa8fec2b3a79c3f23017f9462ca3dd6de94faa853eca35fdbef330331a5f087302ca631642"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 12:42:40.538832) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:42:40.539184) ========= 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.NnVSttjAev/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.flJYnFQXYF/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.004976 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:42:40.589912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 12:42:40.590470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 12:42:40.591063) ========= ========= Total master_lag: 0.19 =========