builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0168 starttime: 1493446552.77 results: success (0) buildid: 20170428172647 builduid: 553b78933779442cbef7b2734bf19b44 revision: f7582c386c562f771a074bf76a1e616a56362184 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.766548) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.767459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.768102) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.805212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.805901) ========= 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.4Qci3VFi2N/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.693ZviULlG/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-04-28 23:15:52-- 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% 66.8M=0s 2017-04-28 23:15:52 (66.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133339 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.968998) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:52.969356) ========= 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.4Qci3VFi2N/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.693ZviULlG/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.028144 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:53.019922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 23:15:53.020241) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev f7582c386c562f771a074bf76a1e616a56362184 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev f7582c386c562f771a074bf76a1e616a56362184 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4Qci3VFi2N/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.693ZviULlG/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-04-28 23:15:53,119 truncating revision to first 12 chars 2017-04-28 23:15:53,119 Setting DEBUG logging. 2017-04-28 23:15:53,119 attempt 1/10 2017-04-28 23:15:53,119 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/f7582c386c56?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 23:15:54,191 unpacking tar archive at: pine-f7582c386c56/testing/mozharness/ program finished with exit code 0 elapsedTime=1.340822 ========= master_lag: 0.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 23:15:54.655514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:54.655948) ========= script_repo_revision: f7582c386c562f771a074bf76a1e616a56362184 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:54.656434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:54.656760) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 23:15:54.677965) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-28 23:15:54.678312) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4Qci3VFi2N/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.693ZviULlG/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:15:54 INFO - MultiFileLogger online at 20170428 23:15:54 in /builds/slave/test 23:15:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 23:15:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:15:54 INFO - {'append_to_log': False, 23:15:54 INFO - 'base_work_dir': '/builds/slave/test', 23:15:54 INFO - 'blob_upload_branch': 'Pine', 23:15:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:15:54 INFO - 'branch': 'Pine', 23:15:54 INFO - 'buildbot_json_path': 'buildprops.json', 23:15:54 INFO - 'config_files': ('talos/mac_config.py',), 23:15:54 INFO - 'default_actions': ('clobber', 23:15:54 INFO - 'read-buildbot-config', 23:15:54 INFO - 'download-and-extract', 23:15:54 INFO - 'populate-webroot', 23:15:54 INFO - 'create-virtualenv', 23:15:54 INFO - 'install', 23:15:54 INFO - 'run-tests'), 23:15:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:15:54 INFO - 'download_minidump_stackwalk': True, 23:15:54 INFO - 'download_symbols': 'ondemand', 23:15:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:15:54 INFO - 'tooltool.py': '/tools/tooltool.py', 23:15:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:15:54 INFO - '/tools/misc-python/virtualenv.py')}, 23:15:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:15:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:15:54 INFO - 'gecko_profile': False, 23:15:54 INFO - 'gecko_profile_interval': 0, 23:15:54 INFO - 'installer_path': 'installer.exe', 23:15:54 INFO - 'log_level': 'info', 23:15:54 INFO - 'log_name': 'talos', 23:15:54 INFO - 'log_to_console': True, 23:15:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:15:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:15:54 INFO - 'opt_config_files': (), 23:15:54 INFO - 'pip_index': False, 23:15:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:15:54 INFO - 'cmd': ('bash', 23:15:54 INFO - '-c', 23:15:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:15:54 INFO - 'enabled': True, 23:15:54 INFO - 'halt_on_failure': False, 23:15:54 INFO - 'name': 'check_screen_resolution'},), 23:15:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:15:54 INFO - 'cmd': ('bash', 23:15:54 INFO - '-c', 23:15:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:15:54 INFO - 'enabled': True, 23:15:54 INFO - 'halt_on_failure': False, 23:15:54 INFO - 'name': 'check_screen_resolution'},), 23:15:54 INFO - 'run_cmd_checks_enabled': True, 23:15:54 INFO - 'suite': 'dromaeojs', 23:15:54 INFO - 'system_bits': '32', 23:15:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:15:54 INFO - 'title': 't-yosemite-r7-0168', 23:15:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:15:54 INFO - 'use_talos_json': True, 23:15:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:15:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:15:54 INFO - 'work_dir': 'build'} 23:15:54 INFO - [mozharness: 2017-04-29 06:15:54.889650Z] Running clobber step. 23:15:54 INFO - Running pre-action listener: _resource_record_pre_action 23:15:54 INFO - Running main action method: clobber 23:15:54 INFO - rmtree: /builds/slave/test/build 23:15:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:15:57 INFO - Running post-action listener: _resource_record_post_action 23:15:57 INFO - [mozharness: 2017-04-29 06:15:57.295099Z] Finished clobber step (success) 23:15:57 INFO - [mozharness: 2017-04-29 06:15:57.295238Z] Running read-buildbot-config step. 23:15:57 INFO - Running pre-action listener: _resource_record_pre_action 23:15:57 INFO - Running main action method: read_buildbot_config 23:15:57 INFO - Using buildbot properties: 23:15:57 INFO - { 23:15:57 INFO - "project": "", 23:15:57 INFO - "product": "firefox", 23:15:57 INFO - "script_repo_revision": "default", 23:15:57 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 23:15:57 INFO - "repository": "", 23:15:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 23:15:57 INFO - "buildid": "20170428172647", 23:15:57 INFO - "pgo_build": "False", 23:15:57 INFO - "basedir": "/builds/slave/test", 23:15:57 INFO - "buildnumber": 8, 23:15:57 INFO - "slavename": "t-yosemite-r7-0168", 23:15:57 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:15:57 INFO - "platform": "yosemite_r7", 23:15:57 INFO - "scheduler": "tests-pine-macosx64-talos", 23:15:57 INFO - "branch": "pine", 23:15:57 INFO - "repo_path": "projects/pine", 23:15:57 INFO - "revision": "f7582c386c562f771a074bf76a1e616a56362184", 23:15:57 INFO - "stage_platform": "macosx64", 23:15:57 INFO - "builduid": "553b78933779442cbef7b2734bf19b44", 23:15:57 INFO - "slavebuilddir": "test" 23:15:57 INFO - } 23:15:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 23:15:57 INFO - Running post-action listener: _resource_record_post_action 23:15:57 INFO - [mozharness: 2017-04-29 06:15:57.298617Z] Finished read-buildbot-config step (success) 23:15:57 INFO - [mozharness: 2017-04-29 06:15:57.298739Z] Running download-and-extract step. 23:15:57 INFO - Running pre-action listener: _resource_record_pre_action 23:15:57 INFO - Running main action method: download_and_extract 23:15:57 INFO - mkdir: /builds/slave/test/build/tests 23:15:57 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')]} 23:15:57 INFO - trying https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 23:15:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 23:15:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 23:15:58 INFO - Downloaded 1325 bytes. 23:15:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 23:15:58 INFO - Using the following test package requirements: 23:15:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 23:15:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:15:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 23:15:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 23:15:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'jsshell-mac.zip'], 23:15:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 23:15:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:15:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 23:15:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 23:15:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 23:15:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:15:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 23:15:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:15:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:15:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:15:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:15:59 INFO - Content-Length response header: 52700518 23:15:59 INFO - Bytes received: 52700518 23:16:16 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:16:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:16:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:16:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:16:17 INFO - Content-Length response header: 52700518 23:16:17 INFO - Bytes received: 52700518 23:16:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 23:16:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:16:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 23:16:34 INFO - Content-Length response header: 14408811 23:16:34 INFO - Bytes received: 14408811 23:16:34 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')]} 23:16:34 INFO - trying https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:16:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 23:16:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 23:16:36 INFO - Downloaded 71205689 bytes. 23:16:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:16:36 INFO - mkdir: /builds/slave/test/properties 23:16:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:16:36 INFO - Writing to file /builds/slave/test/properties/build_url 23:16:36 INFO - Contents: 23:16:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:16:37 INFO - Running post-action listener: _resource_record_post_action 23:16:37 INFO - Running post-action listener: set_extra_try_arguments 23:16:37 INFO - [mozharness: 2017-04-29 06:16:37.226465Z] Finished download-and-extract step (success) 23:16:37 INFO - [mozharness: 2017-04-29 06:16:37.226585Z] Running populate-webroot step. 23:16:37 INFO - Running pre-action listener: _resource_record_pre_action 23:16:37 INFO - Running main action method: populate_webroot 23:16:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:16:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:16:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:16:37 INFO - '--tppagecycles', 23:16:37 INFO - '7'], 23:16:37 INFO - 'tests': ['tsvgm']}}, 23:16:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:16:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:16:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:16:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:16:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:16:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:16:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['damp', 'tps']}, 23:16:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'tests': ['damp', 'tps']}, 23:16:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['dromaeo_dom']}, 23:16:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:16:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 23:16:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 23:16:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['a11yr', 23:16:37 INFO - 'ts_paint', 23:16:37 INFO - 'tpaint', 23:16:37 INFO - 'sessionrestore', 23:16:37 INFO - 'sessionrestore_no_auto_restore', 23:16:37 INFO - 'tabpaint']}, 23:16:37 INFO - 'other-e10s': {'tests': ['a11yr', 23:16:37 INFO - 'ts_paint', 23:16:37 INFO - 'tpaint', 23:16:37 INFO - 'sessionrestore', 23:16:37 INFO - 'sessionrestore_no_auto_restore', 23:16:37 INFO - 'tabpaint']}, 23:16:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:16:37 INFO - 'ts_paint', 23:16:37 INFO - 'tpaint', 23:16:37 INFO - 'sessionrestore', 23:16:37 INFO - 'sessionrestore_no_auto_restore', 23:16:37 INFO - 'tabpaint']}, 23:16:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:16:37 INFO - 'ts_paint', 23:16:37 INFO - 'tpaint', 23:16:37 INFO - 'sessionrestore', 23:16:37 INFO - 'sessionrestore_no_auto_restore', 23:16:37 INFO - 'tabpaint']}, 23:16:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['a11yr', 23:16:37 INFO - 'ts_paint', 23:16:37 INFO - 'tpaint', 23:16:37 INFO - 'sessionrestore', 23:16:37 INFO - 'sessionrestore_no_auto_restore', 23:16:37 INFO - 'tabpaint']}, 23:16:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['a11yr', 23:16:37 INFO - 'ts_paint', 23:16:37 INFO - 'tpaint', 23:16:37 INFO - 'sessionrestore', 23:16:37 INFO - 'sessionrestore_no_auto_restore', 23:16:37 INFO - 'tabpaint']}, 23:16:37 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 23:16:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 23:16:37 INFO - 'bloom_basic_ref']}, 23:16:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['tsvgx', 23:16:37 INFO - 'tsvgr_opacity', 23:16:37 INFO - 'tart', 23:16:37 INFO - 'tscrollx', 23:16:37 INFO - 'cart', 23:16:37 INFO - 'tsvg_static']}, 23:16:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:16:37 INFO - 'tsvgr_opacity', 23:16:37 INFO - 'tart', 23:16:37 INFO - 'tscrollx', 23:16:37 INFO - 'cart', 23:16:37 INFO - 'tsvg_static']}, 23:16:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'talos_options': ['--disable-e10s'], 23:16:37 INFO - 'tests': ['tp5o']}, 23:16:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:16:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'talos_options': ['--disable-e10s', 23:16:37 INFO - '--xperf_path', 23:16:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:16:37 INFO - 'tests': ['tp5n']}, 23:16:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:16:37 INFO - 'talos_options': ['--xperf_path', 23:16:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:16:37 INFO - 'tests': ['tp5n']}}, 23:16:37 INFO - 'talos.zip': {'path': '', 23:16:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:16:37 INFO - Running post-action listener: _resource_record_post_action 23:16:37 INFO - [mozharness: 2017-04-29 06:16:37.245773Z] Finished populate-webroot step (success) 23:16:37 INFO - [mozharness: 2017-04-29 06:16:37.245905Z] Running create-virtualenv step. 23:16:37 INFO - Running pre-action listener: _resource_record_pre_action 23:16:37 INFO - Running main action method: create_virtualenv 23:16:37 INFO - Creating virtualenv /builds/slave/test/build/venv 23:16:37 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 23:16:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:16:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:16:37 INFO - Using real prefix '/tools/python27' 23:16:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:16:38 INFO - Installing distribute.............................................................................................................................................................................................done. 23:16:40 INFO - Installing pip.................done. 23:16:40 INFO - Return code: 0 23:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:16:41 INFO - Reading from file tmpfile_stdout 23:16:41 INFO - Output received: 23:16:41 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) 23:16:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:41 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:41 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 23:16:41 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 23:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:41 INFO - 'HOME': '/Users/cltbld', 23:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:41 INFO - 'LOGNAME': 'cltbld', 23:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:41 INFO - 'MOZ_NO_REMOTE': '1', 23:16:41 INFO - 'NO_EM_RESTART': '1', 23:16:41 INFO - 'PAGER': '/bin/cat', 23:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:41 INFO - 'PWD': '/builds/slave/test', 23:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:41 INFO - 'SHELL': '/bin/bash', 23:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:41 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:41 INFO - 'USER': 'cltbld', 23:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:41 INFO - 'XPC_FLAGS': '0x0', 23:16:41 INFO - 'XPC_SERVICE_NAME': '0', 23:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:41 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 23:16:41 INFO - Cleaning up... 23:16:41 INFO - Return code: 0 23:16:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:41 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:41 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 23:16:41 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 23:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:41 INFO - 'HOME': '/Users/cltbld', 23:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:41 INFO - 'LOGNAME': 'cltbld', 23:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:41 INFO - 'MOZ_NO_REMOTE': '1', 23:16:41 INFO - 'NO_EM_RESTART': '1', 23:16:41 INFO - 'PAGER': '/bin/cat', 23:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:41 INFO - 'PWD': '/builds/slave/test', 23:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:41 INFO - 'SHELL': '/bin/bash', 23:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:41 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:41 INFO - 'USER': 'cltbld', 23:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:41 INFO - 'XPC_FLAGS': '0x0', 23:16:41 INFO - 'XPC_SERVICE_NAME': '0', 23:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:41 INFO - Downloading/unpacking psutil>=3.1.1 23:16:41 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 23:16:41 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 23:16:41 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 23:16:41 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 23:16:41 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 23:16:41 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 23:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:16:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:16:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:16:45 INFO - Installing collected packages: psutil 23:16:45 INFO - Running setup.py install for psutil 23:16:45 INFO - building 'psutil._psutil_osx' extension 23:16:45 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 23:16:45 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 23:16:46 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 23:16:46 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 23:16:46 INFO - building 'psutil._psutil_posix' extension 23:16:46 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 23:16:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:16:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:16:46 INFO - ^ 23:16:46 INFO - 1 warning generated. 23:16:46 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 23:16:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:16:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:16:46 INFO - Successfully installed psutil 23:16:46 INFO - Cleaning up... 23:16:46 INFO - Return code: 0 23:16:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:47 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:47 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 23:16:47 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 23:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:47 INFO - 'HOME': '/Users/cltbld', 23:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:47 INFO - 'LOGNAME': 'cltbld', 23:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:47 INFO - 'MOZ_NO_REMOTE': '1', 23:16:47 INFO - 'NO_EM_RESTART': '1', 23:16:47 INFO - 'PAGER': '/bin/cat', 23:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:47 INFO - 'PWD': '/builds/slave/test', 23:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:47 INFO - 'SHELL': '/bin/bash', 23:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:47 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:47 INFO - 'USER': 'cltbld', 23:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:47 INFO - 'XPC_FLAGS': '0x0', 23:16:47 INFO - 'XPC_SERVICE_NAME': '0', 23:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:16:50 INFO - Installing collected packages: mozsystemmonitor 23:16:50 INFO - Running setup.py install for mozsystemmonitor 23:16:50 INFO - Successfully installed mozsystemmonitor 23:16:50 INFO - Cleaning up... 23:16:50 INFO - Return code: 0 23:16:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:50 INFO - 'HOME': '/Users/cltbld', 23:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:50 INFO - 'LOGNAME': 'cltbld', 23:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:50 INFO - 'MOZ_NO_REMOTE': '1', 23:16:50 INFO - 'NO_EM_RESTART': '1', 23:16:50 INFO - 'PAGER': '/bin/cat', 23:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:50 INFO - 'PWD': '/builds/slave/test', 23:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:50 INFO - 'SHELL': '/bin/bash', 23:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:50 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:50 INFO - 'USER': 'cltbld', 23:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:50 INFO - 'XPC_FLAGS': '0x0', 23:16:50 INFO - 'XPC_SERVICE_NAME': '0', 23:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:51 INFO - Downloading/unpacking jsonschema==2.5.1 23:16:51 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 23:16:51 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 23:16:51 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 23:16:51 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 23:16:51 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 23:16:51 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 23:16:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:16:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:54 INFO - Downloading functools32-3.2.3-2.tar.gz 23:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:16:54 INFO - warning: no files found matching '*.txt' 23:16:54 INFO - no previously-included directories found matching 'build' 23:16:54 INFO - no previously-included directories found matching 'dist' 23:16:54 INFO - no previously-included directories found matching '.git*' 23:16:54 INFO - Installing collected packages: jsonschema, functools32 23:16:54 INFO - Running setup.py install for functools32 23:16:54 INFO - warning: no files found matching '*.txt' 23:16:54 INFO - no previously-included directories found matching 'build' 23:16:54 INFO - no previously-included directories found matching 'dist' 23:16:54 INFO - no previously-included directories found matching '.git*' 23:16:54 INFO - Successfully installed jsonschema functools32 23:16:54 INFO - Cleaning up... 23:16:54 INFO - Return code: 0 23:16:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:16: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')]} 23:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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')]} 23:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:54 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:54 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 23:16:54 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 23:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:54 INFO - 'HOME': '/Users/cltbld', 23:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:54 INFO - 'LOGNAME': 'cltbld', 23:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:54 INFO - 'MOZ_NO_REMOTE': '1', 23:16:54 INFO - 'NO_EM_RESTART': '1', 23:16:54 INFO - 'PAGER': '/bin/cat', 23:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:54 INFO - 'PWD': '/builds/slave/test', 23:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:54 INFO - 'SHELL': '/bin/bash', 23:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:54 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:54 INFO - 'USER': 'cltbld', 23:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:54 INFO - 'XPC_FLAGS': '0x0', 23:16:54 INFO - 'XPC_SERVICE_NAME': '0', 23:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:16:54 INFO - Cleaning up... 23:16:54 INFO - Return code: 0 23:16:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:16: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')]} 23:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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')]} 23:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:54 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:54 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 23:16:54 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 23:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:54 INFO - 'HOME': '/Users/cltbld', 23:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:54 INFO - 'LOGNAME': 'cltbld', 23:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:54 INFO - 'MOZ_NO_REMOTE': '1', 23:16:54 INFO - 'NO_EM_RESTART': '1', 23:16:54 INFO - 'PAGER': '/bin/cat', 23:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:54 INFO - 'PWD': '/builds/slave/test', 23:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:54 INFO - 'SHELL': '/bin/bash', 23:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:54 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:54 INFO - 'USER': 'cltbld', 23:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:54 INFO - 'XPC_FLAGS': '0x0', 23:16:54 INFO - 'XPC_SERVICE_NAME': '0', 23:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:55 INFO - Downloading/unpacking blobuploader==1.2.4 23:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:57 INFO - Downloading blobuploader-1.2.4.tar.gz 23:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:16:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:16:57 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 23:16:57 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 23:16:57 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 23:16:57 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 23:16:57 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 23:16:57 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 23:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:16:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:16:58 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 23:16:58 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 23:16:58 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 23:16:58 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 23:16:58 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 23:16:58 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 23:16:58 INFO - Downloading docopt-0.6.1.tar.gz 23:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:16:59 INFO - Installing collected packages: blobuploader, requests, docopt 23:16:59 INFO - Running setup.py install for blobuploader 23:16:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:16:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:16:59 INFO - Running setup.py install for requests 23:16:59 INFO - Running setup.py install for docopt 23:16:59 INFO - Successfully installed blobuploader requests docopt 23:16:59 INFO - Cleaning up... 23:16:59 INFO - Return code: 0 23:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:59 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:16:59 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 23:16:59 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 23:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:16:59 INFO - 'HOME': '/Users/cltbld', 23:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:16:59 INFO - 'LOGNAME': 'cltbld', 23:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:59 INFO - 'MOZ_NO_REMOTE': '1', 23:16:59 INFO - 'NO_EM_RESTART': '1', 23:16:59 INFO - 'PAGER': '/bin/cat', 23:16:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:59 INFO - 'PWD': '/builds/slave/test', 23:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:16:59 INFO - 'SHELL': '/bin/bash', 23:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:16:59 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:16:59 INFO - 'USER': 'cltbld', 23:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:59 INFO - 'XPC_FLAGS': '0x0', 23:16:59 INFO - 'XPC_SERVICE_NAME': '0', 23:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-xtV9hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Yhvbet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-CN8_Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-np_528-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Hb1pLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-4yANT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ppX4Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-mfV1lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tfh8wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-UJQIEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-77fCkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-jErqkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-MidRvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Rj9XSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-QLfGrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-MFnncT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:01 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-DX9FSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:17:01 INFO - Running setup.py install for manifestparser 23:17:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:17:02 INFO - Running setup.py install for mozcrash 23:17:02 INFO - Running setup.py install for mozdebug 23:17:02 INFO - Running setup.py install for mozdevice 23:17:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:17:02 INFO - Running setup.py install for mozfile 23:17:02 INFO - Running setup.py install for mozhttpd 23:17:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:17:02 INFO - Running setup.py install for mozinfo 23:17:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:17:02 INFO - Running setup.py install for mozInstall 23:17:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Running setup.py install for mozleak 23:17:03 INFO - Running setup.py install for mozlog 23:17:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Running setup.py install for moznetwork 23:17:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Running setup.py install for mozprocess 23:17:03 INFO - Running setup.py install for mozprofile 23:17:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:17:03 INFO - Running setup.py install for mozrunner 23:17:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:17:04 INFO - Running setup.py install for mozscreenshot 23:17:04 INFO - Running setup.py install for moztest 23:17:04 INFO - Running setup.py install for mozversion 23:17:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:17:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:17:04 INFO - Cleaning up... 23:17:04 INFO - Return code: 0 23:17:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:04 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:17:04 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 23:17:04 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 23:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:17:04 INFO - 'HOME': '/Users/cltbld', 23:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:17:04 INFO - 'LOGNAME': 'cltbld', 23:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:04 INFO - 'MOZ_NO_REMOTE': '1', 23:17:04 INFO - 'NO_EM_RESTART': '1', 23:17:04 INFO - 'PAGER': '/bin/cat', 23:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:04 INFO - 'PWD': '/builds/slave/test', 23:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:17:04 INFO - 'SHELL': '/bin/bash', 23:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:17:04 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:17:04 INFO - 'USER': 'cltbld', 23:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:04 INFO - 'XPC_FLAGS': '0x0', 23:17:04 INFO - 'XPC_SERVICE_NAME': '0', 23:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-7eBEAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:04 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)) 23:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-SV4kT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:04 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)) 23:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-PDUnio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-nNTo9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-pIU7Ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-mvth5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-fvNaLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-i8Fqzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Vdzrez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-vQSlYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-TcctRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:05 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)) 23:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ORkfXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 23:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:06 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-zWPZkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:06 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)) 23:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:06 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-AUigrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:06 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)) 23:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:06 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-BLb4d9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:06 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)) 23:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:06 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-8JqRT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:06 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)) 23:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:06 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tXhXmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:06 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)) 23:17:06 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)) 23:17:06 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)) 23:17:06 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)) 23:17:06 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:17:06 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:17:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:17:06 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 23:17:06 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 23:17:06 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 23:17:06 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 23:17:06 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 23:17:06 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 23:17:09 INFO - Downloading blessings-1.6.tar.gz 23:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:17:09 INFO - Installing collected packages: blessings 23:17:09 INFO - Running setup.py install for blessings 23:17:09 INFO - Successfully installed blessings 23:17:09 INFO - Cleaning up... 23:17:09 INFO - Return code: 0 23:17:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:17:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:17:09 INFO - Reading from file tmpfile_stdout 23:17:09 INFO - Current package versions: 23:17:09 INFO - blessings == 1.6 23:17:09 INFO - blobuploader == 1.2.4 23:17:09 INFO - docopt == 0.6.1 23:17:09 INFO - functools32 == 3.2.3-2 23:17:09 INFO - jsonschema == 2.5.1 23:17:09 INFO - manifestparser == 1.1 23:17:09 INFO - mozInstall == 1.12 23:17:09 INFO - mozcrash == 1.0 23:17:09 INFO - mozdebug == 0.1 23:17:09 INFO - mozdevice == 0.50 23:17:09 INFO - mozfile == 1.2 23:17:09 INFO - mozhttpd == 0.7 23:17:09 INFO - mozinfo == 0.9 23:17:09 INFO - mozleak == 0.1 23:17:09 INFO - mozlog == 3.4 23:17:09 INFO - moznetwork == 0.27 23:17:09 INFO - mozprocess == 0.25 23:17:09 INFO - mozprofile == 0.28 23:17:09 INFO - mozrunner == 6.13 23:17:09 INFO - mozscreenshot == 0.1 23:17:09 INFO - mozsystemmonitor == 0.3 23:17:09 INFO - moztest == 0.8 23:17:09 INFO - mozversion == 1.4 23:17:09 INFO - psutil == 3.1.1 23:17:09 INFO - requests == 1.2.3 23:17:09 INFO - wsgiref == 0.1.2 23:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17: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')]} 23:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17: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')]} 23:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:09 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:17:09 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 23:17:09 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 23:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:17:09 INFO - 'HOME': '/Users/cltbld', 23:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:17:09 INFO - 'LOGNAME': 'cltbld', 23:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:09 INFO - 'MOZ_NO_REMOTE': '1', 23:17:09 INFO - 'NO_EM_RESTART': '1', 23:17:09 INFO - 'PAGER': '/bin/cat', 23:17:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:09 INFO - 'PWD': '/builds/slave/test', 23:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:17:09 INFO - 'SHELL': '/bin/bash', 23:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:17:09 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:17:09 INFO - 'USER': 'cltbld', 23:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:09 INFO - 'XPC_FLAGS': '0x0', 23:17:09 INFO - 'XPC_SERVICE_NAME': '0', 23:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 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)) 23:17:10 INFO - Cleaning up... 23:17:10 INFO - Return code: 0 23:17:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:17:10 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')]} 23:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:10 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')]} 23:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:10 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 0x108489b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10848c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab1c8a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, '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.4Qci3VFi2N/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.693ZviULlG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 23:17:10 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 23:17:10 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 23:17:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:17:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:17:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:17:10 INFO - 'HOME': '/Users/cltbld', 23:17:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:17:10 INFO - 'LOGNAME': 'cltbld', 23:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:10 INFO - 'MOZ_NO_REMOTE': '1', 23:17:10 INFO - 'NO_EM_RESTART': '1', 23:17:10 INFO - 'PAGER': '/bin/cat', 23:17:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:10 INFO - 'PWD': '/builds/slave/test', 23:17:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:17:10 INFO - 'SHELL': '/bin/bash', 23:17:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:17:10 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:17:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:17:10 INFO - 'USER': 'cltbld', 23:17:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:17:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:10 INFO - 'XPC_FLAGS': '0x0', 23:17:10 INFO - 'XPC_SERVICE_NAME': '0', 23:17:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:17:10 INFO - Cleaning up... 23:17:10 INFO - Return code: 0 23:17:10 INFO - Running post-action listener: _resource_record_post_action 23:17:10 INFO - Running post-action listener: _start_resource_monitoring 23:17:10 INFO - Starting resource monitoring. 23:17:10 INFO - [mozharness: 2017-04-29 06:17:10.396563Z] Finished create-virtualenv step (success) 23:17:10 INFO - [mozharness: 2017-04-29 06:17:10.397480Z] Running install step. 23:17:10 INFO - Running pre-action listener: _resource_record_pre_action 23:17:10 INFO - Running main action method: install 23:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:17:10 INFO - Reading from file tmpfile_stdout 23:17:10 INFO - Detecting whether we're running mozinstall >=1.0... 23:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:17:10 INFO - Reading from file tmpfile_stdout 23:17:10 INFO - Output received: 23:17:10 INFO - Usage: mozinstall [options] installer 23:17:10 INFO - Options: 23:17:10 INFO - -h, --help show this help message and exit 23:17:10 INFO - -d DEST, --destination=DEST 23:17:10 INFO - Directory to install application into. [default: 23:17:10 INFO - "/builds/slave/test"] 23:17:10 INFO - --app=APP Application being installed. [default: firefox] 23:17:10 INFO - mkdir: /builds/slave/test/build/application 23:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:17:30 INFO - Reading from file tmpfile_stdout 23:17:30 INFO - Output received: 23:17:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:17:30 INFO - Running post-action listener: _resource_record_post_action 23:17:30 INFO - [mozharness: 2017-04-29 06:17:30.567535Z] Finished install step (success) 23:17:30 INFO - [mozharness: 2017-04-29 06:17:30.567656Z] Running run-tests step. 23:17:30 INFO - Running pre-action listener: _resource_record_pre_action 23:17:30 INFO - Running main action method: run_tests 23:17:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:17:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:17:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:17:30 INFO - 2017-04-28 23:17:30.582 screenresolution[1615:7654] starting screenresolution argv=screenresolution get 23:17:30 INFO - 2017-04-28 23:17:30.584 screenresolution[1615:7654] Display 0: 1600x1200x32@60 23:17:30 INFO - 2017-04-28 23:17:30.590 screenresolution[1616:7657] starting screenresolution argv=screenresolution list 23:17:30 INFO - Available Modes on Display 0 23:17:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:17:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:17:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:17:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:17:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:17:30 INFO - Intel Iris: 23:17:30 INFO - Chipset Model: Intel Iris 23:17:30 INFO - Type: GPU 23:17:30 INFO - Bus: Built-In 23:17:30 INFO - VRAM (Dynamic, Max): 1536 MB 23:17:30 INFO - Vendor: Intel (0x8086) 23:17:30 INFO - Device ID: 0x0a2e 23:17:30 INFO - Revision ID: 0x0009 23:17:30 INFO - Displays: 23:17:30 INFO - Display: 23:17:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:17:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:17:30 INFO - Display Serial Number: 1600x1200 60 23:17:30 INFO - Main Display: Yes 23:17:30 INFO - Mirror: Off 23:17:30 INFO - Online: Yes 23:17:30 INFO - Rotation: Supported 23:17:30 INFO - Return code: 0 23:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:17:30 INFO - Python 2.7.3 23:17:30 INFO - Return code: 0 23:17:30 INFO - grabbing minidump binary from tooltool 23:17:30 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')]} 23:17:30 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 0x108267d30>, '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 0x10818ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10825cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:17:30 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 23:17:30 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 23:17:30 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 23:17:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:17:30 INFO - Return code: 0 23:17:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:17:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:17:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:17:30 INFO - ENV: RUST_BACKTRACE is now 1 23:17:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:17:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 23:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0168 --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 23:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4Qci3VFi2N/Render', 23:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:17:30 INFO - 'HOME': '/Users/cltbld', 23:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:17:30 INFO - 'LOGNAME': 'cltbld', 23:17:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:17:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:30 INFO - 'MOZ_NO_REMOTE': '1', 23:17:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:17:30 INFO - 'NO_EM_RESTART': '1', 23:17:30 INFO - 'PAGER': '/bin/cat', 23:17:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:30 INFO - 'PWD': '/builds/slave/test', 23:17:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:17:30 INFO - 'RUST_BACKTRACE': '1', 23:17:30 INFO - 'SHELL': '/bin/bash', 23:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.693ZviULlG/Listeners', 23:17:30 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 23:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:17:30 INFO - 'USER': 'cltbld', 23:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:30 INFO - 'XPC_FLAGS': '0x0', 23:17:30 INFO - 'XPC_SERVICE_NAME': '0', 23:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:17:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 23:17:31 INFO - mozversion application_buildid: 20170428172647 23:17:31 INFO - mozversion application_changeset: f7582c386c562f771a074bf76a1e616a56362184 23:17:31 INFO - mozversion application_display_name: Nightly 23:17:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:17:31 INFO - mozversion application_name: Firefox 23:17:31 INFO - mozversion application_remotingname: firefox 23:17:31 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 23:17:31 INFO - mozversion application_vendor: Mozilla 23:17:31 INFO - mozversion application_version: 55.0a1 23:17:31 INFO - mozversion platform_buildid: 20170428172647 23:17:31 INFO - mozversion platform_changeset: f7582c386c562f771a074bf76a1e616a56362184 23:17:31 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 23:17:31 INFO - mozversion platform_version: 55.0a1 23:17:31 INFO - using testdate: 1493446651 23:17:31 INFO - actual date: 1493446651 23:17:31 INFO - starting webserver on 'localhost:49233' 23:17:31 INFO - SUITE-START | Running 2 tests 23:17:31 INFO - TEST-START | dromaeo_css 23:17:31 INFO - operating with platform_type : mac_ 23:17:31 INFO - Initialising browser for dromaeo_css test... 23:17:31 INFO - TEST-INFO | started process 1626 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpKP0z8Q/profile http://localhost:49233/getInfo.html) 23:17:33 INFO - PID 1626 | __metrics Screen width/height:1600/1200 23:17:33 INFO - PID 1626 | colorDepth:24 23:17:33 INFO - PID 1626 | Browser inner width/height: 1024/689 23:17:33 INFO - PID 1626 | __metrics 23:17:33 INFO - PID 1626 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:17:33 INFO - PID 1626 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:17:34 INFO - TEST-INFO | 1626: exit 0 23:17:34 INFO - Browser initialized. 23:17:34 INFO - Running cycle 1/1 for dromaeo_css test... 23:17:34 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpKP0z8Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:17:47 INFO - PID 1628 | RSS: Main: 241381376 23:17:47 INFO - PID 1628 | 23:20:51 INFO - PID 1628 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 23:20:53 INFO - PID 1628 | RSS: Main: 406237184 23:20:53 INFO - PID 1628 | 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | A coding exception was thrown and uncaught in a Task. 23:22:36 INFO - PID 1628 | 23:22:36 ERROR - PID 1628 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:22:36 INFO - PID 1628 | Full stack: 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | A coding exception was thrown and uncaught in a Task. 23:22:36 INFO - PID 1628 | 23:22:36 ERROR - PID 1628 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:22:36 INFO - PID 1628 | Full stack: 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | A coding exception was thrown and uncaught in a Task. 23:22:36 INFO - PID 1628 | 23:22:36 ERROR - PID 1628 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:22:36 INFO - PID 1628 | Full stack: 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | ************************* 23:22:36 INFO - PID 1628 | A coding exception was thrown and uncaught in a Task. 23:22:36 INFO - PID 1628 | 23:22:36 ERROR - PID 1628 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:22:36 INFO - PID 1628 | Full stack: 23:22:36 INFO - PID 1628 | ************************* 23:23:55 INFO - PID 1628 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 23:23:56 INFO - PID 1628 | RSS: Main: 365531136 23:23:56 INFO - PID 1628 | 23:26:58 INFO - PID 1628 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 23:26:59 INFO - PID 1628 | RSS: Main: 328278016 23:26:59 INFO - PID 1628 | 23:30:01 INFO - PID 1628 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 23:30:01 INFO - PID 1628 | RSS: Main: 331456512 23:30:01 INFO - PID 1628 | 23:33:05 INFO - PID 1628 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 23:33:05 INFO - PID 1628 | RSS: Main: 341430272 23:33:05 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 23:36:08 INFO - PID 1628 | RSS: Main: 379027456 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | __start_tp_report 23:36:08 INFO - PID 1628 | _x_x_mozilla_page_load 23:36:08 INFO - PID 1628 | _x_x_mozilla_page_load_details 23:36:08 INFO - PID 1628 | |i|pagename|runs| 23:36:08 INFO - PID 1628 | |0;dojo.html;2827;3419.640937701541;3422.828858557073;3445.3626079175338;3467.75725699201;5676.063449530822;5823.06830907055;6019.608725432844;6042.818715438539;6045.244344456943;5705.315362156547;5716.971415142929;5717.742701578429;5729.799457019005;5736.942630573693;5049.67177133487;5073.264376665391;5099.388073431193;5111.565516931066;5115.769790359424;5185.974070129644;5192.169252919533;5197.688138711689;5210.322658054448;5212.556932660709;5198.6880787152895;5199.532042116209;5204.9739751301195;5214.556762675177;5222.190560463135;5171.250168725552;5197.038547868638;5200;5200.32395788547;5200.531952124327;5069.391672999227;5138.126518491866;5157.8452646420665;5163.63854530183;5216.634835561512;5957.076653118774;6018.729157187937;6044.395560443921;6080.695965201722;6089.238845144357;6241.188645476104;6339.080833279195;6341.270753863272;6342.429181373698;6349.555531112824;5811.4479124483105;6331.398517140864;6339.302676705559;6361.554691171574;6427.903581446282;6075.27096748393;6091.0863370494335;6094.116353128771;6107.969460152715;6110.725017374273;6533.640649764308;6569.638669873203;6575.671216439159;6589.308122647149;6589.835254118685;6175.475084617858;6303.054541818672;6310.337414571404;6333.20834895638;6336.17629708135;6257.561970662101;6287.2140982377205;6307.117003619497;6380.361963803675;6382.521310901608;6339.175907132043;6348.746050157942;6350.8412289692205;6354.745810167634;6355.491560675135;5023.447420783711;6270.122182894398;6320.873582528324;6339.492840572743;6366.936330636727;4963.478834722375;5052;5074.619403544749;5116.8209112681625;5138.58891288696;4532.116237333688;4540.205464043779;4551.544845515422;4562.61217796491;4591.816327346935;6074.42292982166;6075.2405949256345;6082.726277317532;6086.573939824259;6097.969510152421;6190.288116866528;6324.114623952651;6416.615003099829;6423.261324947597;6424.967875160594;6032.668203248863;6073.6659483727935;6088.51291896656;6118.847028824226;6131.202943617302;5596.188552659842;5605.103183490622;5619.297587801525;5623.6906970116215;5650.915236271459;6438.259600146043;6619.735210591522;6647.368499992493;6656.667166641745;6666.133402657721;6439.613623182624;6452.387023232785;6455.806325810233;6460.127882735958;6463.062855885964;6607;6638.568493048129;6639;6647.800565983028;6662.133922590032;6190.845228869314;6256.405641464054;6300.621962682254;6334.809955701337;6360.650164241011;6237.251529816451;6400.807975760735;6458.321876203119;6463.612183269019;6464.256610489854;6311;6341.524385671001;6344.555881088464;6352.460040896575;6357.046443033582;6147.354527774698;6179.28938172098;6233.875322493615;6237.064440333987;6323.241211054703;6232.127502149793;6233.314335423009;6241.251049874044;6263.655498947602;6264.373562643699;6265.631694849344;6306.779262725782;6317.905231421624;6334.778282760081;6338.524610654127;6019;6119.41115851624;6190.876182476235;6249.281332646622;6250.312465628686 23:36:08 INFO - PID 1628 | |1;ext.html;777.4752042371398;801.9117897031327;810.4731924249237;1118.3010618363523;1132.5413207650904;26518.867405662968;36129.45805812915;36499.452508212366;36551.817240913755;36566.45150322747;38561.228775424475;38918;38923.61076389242;38993.22013559727;39014.02464938378;6037.698115094249;8270.793230169234;8457.365697572675;8462.069172391031;8483.618237179311;6003.849903752419;6040.939590604104;6074.179985701918;6077.878442431149;6087.756489740427;8279.668813247494;8463.703770368038;8466.153384661546;8466.534340611264;8521.573921303941;8417.83164336713;8487.066422693499;8512.787180320542;8516.148385161465;8525.65897364111;5876.7355469004;5993.190919225893;8255.133211012817;8327.292180164693;8371.832563348698;34970;35289.94130176099;35357;35404.82297588521;35450;3541.557305336833;3565.358235517606;3571.785692858437;3573.928521429583;3578.4453409721536;24557.38606534851;30358;30374.240643983856;30463.543046854316;30515.542266866014;3032.181311046027;3125.9062228133193;3130.9217269568217;3145.433821912055;3146.480830662938;229335.1864257636;292825;295994.52002740063;296129;296343;37509.62490375116;47967.760161198974;47969.5203047972;48124.27813582799;48185;50083.24875126876;50231.49768502268;50282.49717502851;50323;50338;13850.584482465541;14003.929980350033;14011.859881401055;14016.439342426393;14032.649183770283;11457.713557161138;11477.081833453303;11492.138089643311;11503.539858405737;11504.079673626089;9497.477638729799;10080.14318782897;10141.746456338504;11731.88268117325;11931.940340298417;5476.835694929158;5523.238380809596;5525.696086715269;5530;5532.336119665666;972.8978457261885;978.4667356290838;979.8579206015088;997.5261750668519;1014.0873214107215;22902.312930612108;23085.422864428187;23282.185123520594;23307.302669841258;23368.766312337;950.7813202963357;1036.549101141006;1077.8706555213425;1086.3970496374588;1109.6449136276476;11695.649130525928;11717.12121590884;11829.408529573659;11858.88141118595;11871.584494542649;298919;298948.50525747234;299107;299120;299608;41625;51563.48436515662;51663.74168129211;51701.48298517042;51758.706032349495;1448.3554818106052;1452.7602945514084;1481.0299253988655;1491.1723993183887;1492.4925525321444;22633;22668.546629066997;22727.659085114068;22753.77246227583;23013.734244616706;34664.480032800035;34691.653083468846;34719.65280347164;34762.82618586891;34778.82610586931;266526;266894.6655266711;267295;267354;268464.65767671034;32897.506537402434;32938.50592241166;32954.67045329516;32977.18625475526;32979.83510082434;42327.788361057996;43270;43442.565574343855;43479.782601086794;43578.56421435745;23165.536689265784;29388;29445.055494450382;30188.94338698135;30381.088567343017;29370.559441607966;29454;30058.098257052323;30204.5469317956;30378.544321834746;36243.81878090592;36319.63680363163;36327.09182270464;36386;36569.45145822762;28758.993435229666;29264.707352926198;29875.253118672208;30706.23234419068;30764.077077687703;23804.761952380253;23827;24040;24095;24168.637470437607 23:36:08 INFO - PID 1628 | |2;jquery.html;382.7779887665154;394.8124640795624;417.6804744370555;435.19271750902084;448.3073651208882;11727.237729547584;15205.315760790763;21345.679814802752;21941.670874936888;22013.559728805434;21746.782532174675;21971.450713732163;22040.008199631015;22216.666749998723;22574.322770316874;5646.378898321182;5831.842218379195;5914.585978981473;5993.700314984255;5995.700214989255;3489.459133834247;5883.646981181121;5986.401359864;6008.639481631094;6013.849653758647;3567.0547304964207;3635.345917607482;5946.643201407929;5955.196048533436;5963.0459126539545;5683.715814209314;5916.2900451945825;5918.348981612041;5922.3781502942;5923.318818335903;5182.844514664566;5289.523942845124;5300.628955973045;5304.124819404794;5304.336957880265;26102.34744131393;26527.46945061088;26757.598636020477;26824.865875670497;26836.597451038055;2163.0915015693445;2195.3413975807352;2200.8899555022344;2201.7027701260286;2206.3050139206257;16542.917285413616;17011.14944252795;17245.965242085516;17272.30910763568;17422.56443588908;2080.843936704753;2095;2138.193226723416;2142.2180903970093;2147.1304121830685;160870.19564902218;161279;163021;163349.3665063346;165104.9999999988;9825.508724563886;11753.236039657395;11821.113416493798;11963.222390544586;12115.454804533818;15971.760423593656;16016.839831601768;16018.839811601967;16031.278592463366;16046.358145674043;7652.426068044919;7929.365650748042;8026.277635012877;8608.34145488165;8855.33584981129;8296.917030829734;8304.70933517336;8465.365097617703;8478.406511544255;8499.980002399752;7439.776806695808;7553.489302139594;7625.161232264447;7652.020949106433;7656.196099409593;2108.0619124489763;2111.7571479279773;2113.2075471698113;2116.322776711468;2307.4692820651007;776.5728849132954;822.4982760516081;864.1919804982361;908.9909100909039;929.5073610986053;15440.613984650248;17243.3964811231;18062.18720157596;18660.53348666267;20193.192272308945;875.3085062800444;928.2620316848122;946.8437707778277;952.1480719999535;963.0754475703263;8872.600732967032;9540.713778586654;10454.947725261325;10496.055355018085;10559.208059395576;518968;531644;539494;550733;551644;103989;106517.40223896648;107196;108768;124244;1133.5749094089717;1170.9180357374903;1196.2642974570751;1196.7008247938015;1197.9760404791855;18718.438446846616;19376.35300999431;21462.570748585254;21468.248611298528;21517.354479365644;43411.34882976821;54834.2142722059;57756.13365799432;58105.70947145237;58386;73064;74138;75434.24565754272;75458.24541754732;77051.2294877044;12251.632451026484;12261.325627090595;12262.938685306517;12590.874091258971;12623.87376126227;13417.675082664484;13457.259850707913;13708.65728356799;13845.446182152602;13909.930450348089;11571.710707232387;11827.645170644895;11828.290302581872;11837.76324473523;11946.880531194924;10036.89963100389;10915.563377464812;10925.836112458479;11244;11283.605073822377;9582.808343833223;11803.468843901874;11851.9333260007;11892.643220703392;12233.388330583613;12292.38538073075;12382.442790074649;12464.127511074137;12736.23582585048;12893.742125157256;11789.764204715686;11853.76292474163;12036.75926481483;12134.453949572115;12262.509499620272 23:36:08 INFO - PID 1628 | |3;mootools.html;1380.116725295811;1439.366678661389;1458.766597344425;1461.189040082755;1461.371610207611;10919;11358.977692007727;11365.090792736582;11365.659030229106;11369;11060.723481912935;11562.53749850007;11694.415279236047;11726.882731172685;11771.176017678767;2503.2490252924094;2549.247971848309;2554.9397000244967;2555.514452254066;2559.2706078767555;2071.4510654676524;2394.928152155438;2530.430653103046;2530.607755797854;2533.290678609992;2136.0494579911947;2321.466062805556;2482.056818409002;2482.466269752003;2486.490269494758;2438.5244877248942;2466.4943686544216;2474.3195621204222;2477;2477.690288713911;2478.21936090131;2481.4168670362633;2484.441000774811;2487.7139129000216;2487.8631675257852;11275.379854108021;11540.192186546945;11551.942240288829;11560.884391156149;11573.247738896946;6475.51433642474;6711.161104861892;6718.966405167992;6730.091437655951;6733.528652994243;3655.9086022849374;3841.8847434577006;4122.896927576805;4243.469566304212;4312.460942382202;1219.5548624751902;1224.3327386574338;1243.9689007774787;1266.639007882749;1269.479513399511;16188.75716864248;16340.428085016967;16361.590960226089;16362.345506179738;16382.0170789753;5251.894962100813;5322.426802898417;5405.5135037846785;5526.392096869341;5564.638298510584;11650.126240531994;11710.238834475902;11719.472623731952;11774.411279435993;11781.646550603497;6488.124103246096;6490.350964903472;6503.057056726842;6509.479241660656;6510.316416776265;6039.516838652898;6109.725062372204;6228.626282423069;6233.625982441068;6241.96879015602;4160.521540022936;4263.186297043936;4272.273713468718;4300.204462174508;4310.094880075157;1215.604928398274;1237.4740735187509;1251.8310028146266;1258.3079306381453;1264.165650670553;645.8012995387115;652.2010537092025;748.0686545251197;761.8438220164853;766.2452484302958;6447.484201263887;6529.510286728514;6571.1289758825205;6599.967000164968;6689.732410703517;485.4926601701231;585.5520526797004;588.2646691635456;593.4955288005161;692.7506097804786;3861.304965106308;4104.056067104582;4110.177964407071;4137.710360274812;4168.854090106832;14482.275886205647;14926.626834329229;15032.473863414729;15060.774088388684;15110.01784883979;14492.855071449361;14839.70320593582;14978.17620030887;14985.250737463084;15282.006669566443;8432;8576.614052367659;8632.964044314722;8690.522021288763;8693.174148455886;5886.793962211302;6357.96821015892;6457.257415397289;6501.739930402731;6554.80335589933;10289.74275643115;13002.154859934075;13182.868171318163;13429.798553021908;13548.932255338661;11214;13578.864211358155;13711.382987765774;13801.480622441615;14063.507777227747;8960.238379737795;8967.551622418983;9078.09219078087;9359.953200233955;9366.829146356706;10355.741106472398;10501.42242176657;10602.840957385491;10604.257701961098;10668.786624267626;8867.512286824287;8904.97592776813;8925.910740892508;8964.820703586021;9076.228520575825;8728.91271087281;8768.561571921506;8781.20969112783;8827.69103081389;8978.281737461119;6804.863902721819;7920.643571039202;8095.87856182146;8096.797580060545;8096.878546821685;5249.685018898878;5828.94171058284;5920;5994.490468310242;6023.698815059317;5112.488751124858;5855;6008.609440386361;6028.3971602839365;6096.939030609815 23:36:08 INFO - PID 1628 | |4;prototype.html;392.96070392960706;397.3642172523962;400.8551576696953;405.0259126800047;408.37722473228325;5938.287405511339;5961.552883533742;5990.221271234745;6005.4294841989995;6014.759409623615;4753.572178503934;5384.3538775346915;5816.534677225823;5838.357780644125;5892.675902825343;4500.9041771987495;4544.613892762706;4559.954400456003;4561.133384656921;4563.58528856054;4541.458541458542;4558.703684260529;4565.1098035883015;4569.748663823489;4588.655850811202;4488.573585509372;4519.508562174475;4548.272276435771;4551.249043907751;4561.521040290771;4484.8206071757095;4524.796384162721;4526.977365113154;4554.064522577891;4563.566461186182;4042.353223484258;4047.7249666355297;4067;4067.5118985721606;4071.9389209161886;5610.887782244332;5762.567807414461;5787.971060144714;5799.333076696192;5799.507041901444;4601.722147536957;5358.035553600351;5443.891122177534;5461.972690136564;5473.480019398191;6023.518118550505;6117.113018612321;6328.335524769925;6334.303226645112;6372;4181.686373521968;4317.935230971538;4552.772361381951;4584.885377865547;4599.241125214336;5530.640508366943;6596.406323430914;6616.93383066168;6626.966865165643;6653.500987425962;4111.876643700693;4453.265211240174;4520.16933142078;4690.296455531698;4696.896229386091;3968.5634580196156;4661.417322834645;4767.594754445911;4788.209945359046;4821.927671084936;4339.696221264481;5438.700871452029;5452.645578037414;5462.699551524624;5480.6437581557075;6253.280872699698;6322.778702745381;6366.78477368935;6385.010323399881;6395.072714456378;1501.2943916359293;1623.1802939515705;1642.5072478256475;1642.5647203490846;1653.9586510337097;939.6476321379482;1081.4268437728017;1127.639155470258;1129.9604513842141;1136.6703655939684;4974.328465657162;5955.302738719444;5955.52355811534;5965.612235204698;5990;6365.708344995798;6460.127882735865;6492.707828147744;6502.284748677642;6521.271862956321;4925.236588328815;4930.161872481687;5195.090859099642;5789.2763404574425;5883.382244864313;3100.534919762054;3173.8574113319187;3366.7474939379645;3410.761246712756;3435.3301106284534;6109.6639684817765;6248.656323902138;6656.933430665728;6711.395974362331;6761.4893861502715;5476.986757449885;6521.347865213535;6618.172728408949;6675.766348177887;6754.257031726506;5835.883282334415;6172.4136207060255;6284.842878927972;6407.942689456281;6658.600483970978;5528.087865502147;5663.32040155184;5743.655380677173;6484.740610375532;6574.704138313692;5273.973630131824;5302.416734159162;6209.658468784261;6369.522285828489;6426.614403135827;6087.269527656709;6130.356312587114;6683.6324002180345;6751.324867513209;6791.830204244933;4449.888752781207;4503.842365517191;4648.721076735406;4672.439307283148;4680.157571637138;5338;5740.626859254302;6097.329293777592;6210.006398976142;6514.837129071621;6235.812925612239;6288.308286088435;6295.968520157553;6296.496280297657;6361.268454127835;5153.639245252946;6100.938990610037;6143.477804386498;6189.6286222826775;6221.253449586078;5058.9494105058475;5119.462456442169;6079.878402432015;6134.723937422916;6175.560653541736;5296.470352964827;5996.640201587918;6066.3630318815885;6267.185265915402;6283.900317444521;5368.489993450616;6084.787032453843;6087.4521293083835;6126.855600539775;6147.293061298008 23:36:08 INFO - PID 1628 | |5;yui.html;707.9610621415826;852.9402941794074;853.1766844994579;857.7383897911138;874.5627186406795;13364.732705345887;18300.816991830077;18409.63180736386;18953.43139705811;18981.14584843682;17170.914145429288;17453.56366090845;17551.38570150045;17562.034088125147;17602.471925842245;939.5912777941583;970.3886551472598;972.5866506734609;973.7711637765119;976.9853452198187;655.3217419970332;659.0707102984792;674.9358810912955;986.8865080515761;998.9400635961829;744.0178963767829;745.619733935694;907.1155623267327;986.9506524673774;1003.8494225866142;979.1774909076339;981.7398389426808;982.6609819612222;982.7395740128871;1016.374929418406;729.4492658043197;729.8248420379124;730.3317464519957;739.8298391370015;756.5687558091889;15992.36030558788;16236.107014114219;16530.504084877473;16987.40544080951;17244.827551724575;16737.581560461087;16782.412615558398;17109.486715398558;17198.3980560681;17368.305267789274;13724.862751372459;13890.097143685629;14084.436622535086;14087.718245635133;14110.435582576685;3393.4061539230784;3419.982900085484;3420.504026916108;3421.9657803421896;3428.0401487583517;123306;123791;125351.37324313412;126361;127252.36373818164;11981.460834262305;12054;12091.697707557205;12208.633740987785;12213.877861221452;20897.47756306074;21048;21093.78906210949;21255.78744212538;21408.78591214068;7551.509151905108;7627.809304767426;7632.427567932427;7632.847343053108;7652;3731.1791405890663;3794.5067141271456;3830.502034735522;3858.6720128789366;3865.942010869839;4153.0447996960265;4186.455760751083;4231.661467082626;4235.152969406131;4241.830326786956;858.2961971183694;876.4609764994483;877.4559772940818;879.2438502887512;880.1858281089966;652.7160685101901;668.8327918020495;675.5743881354715;677.5291172634971;684.2473279392727;10577.31247468912;11394.886051139549;11860.525578976916;11896.286222826659;12056.819147712791;627.7175271128011;661.441082285468;667.0594461808923;674.6828990374516;694.7221111555317;5393;5577.247071645357;5611.2144299798065;5694;5719.170720245541;107345;108245;108367;110560;111116;89149;95871;97102.5144874271;97525.5123724391;98566;55294;67804.32195677981;78449.60775196088;80499.95000500021;80712.59643701861;10641.787164256517;10911.836322455329;11081.33511989251;11171.832422513506;11209.943950280196;40609.984750381474;40742.79628601838;41125.588744112174;41630;42313.519026833914;52572;53194.73402632962;53428.46571534235;53521.19718204152;55674.16488752753;12085.335306558223;14299.57101286963;14482.98619096653;14647.267636617913;14795;15465.458708945132;15478.303476343366;15715.214239287761;15918.68162636764;15956.202189890691;14458.493952711604;14721.705565888407;14802.555923322318;14900.478483253035;14905.776413354026;14331.641708957359;14804.7779283313;14893.553193404216;15013.549593512213;15068.773968390264;11657.242279251821;11989.520419183134;12105.273683579013;12122.878771212176;12129.090318226345;10360.430176340373;10401.636481564696;10598.145324568015;10998.615048473264;11005.119590432587;11382.430878456209;11577.942110289394;11939.164258501822;12141.757164856477;12199.695007624525 23:36:08 INFO - PID 1628 | __end_tp_report 23:36:08 INFO - PID 1628 | __start_cc_report 23:36:08 INFO - PID 1628 | _x_x_mozilla_cycle_collect,1657 23:36:08 INFO - PID 1628 | __end_cc_report 23:36:08 INFO - PID 1628 | __startTimestamp1493447768465__endTimestamp 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | ------- Summary: start ------- 23:36:08 INFO - PID 1628 | Number of tests: 6 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | [#0] dojo.html Cycles:165 Average:5895.94 Median:6190.29 stddev:709.34 (11.5%) stddev-sans-first:669.41 23:36:08 INFO - PID 1628 | Values: 2827.0 3419.6 3422.8 3445.4 3467.8 5676.1 5823.1 6019.6 6042.8 6045.2 5705.3 5717.0 5717.7 5729.8 5736.9 5049.7 5073.3 5099.4 5111.6 5115.8 5186.0 5192.2 5197.7 5210.3 5212.6 5198.7 5199.5 5205.0 5214.6 5222.2 5171.3 5197.0 5200.0 5200.3 5200.5 5069.4 5138.1 5157.8 5163.6 5216.6 5957.1 6018.7 6044.4 6080.7 6089.2 6241.2 6339.1 6341.3 6342.4 6349.6 5811.4 6331.4 6339.3 6361.6 6427.9 6075.3 6091.1 6094.1 6108.0 6110.7 6533.6 6569.6 6575.7 6589.3 6589.8 6175.5 6303.1 6310.3 6333.2 6336.2 6257.6 6287.2 6307.1 6380.4 6382.5 6339.2 6348.7 6350.8 6354.7 6355.5 5023.4 6270.1 6320.9 6339.5 6366.9 4963.5 5052.0 5074.6 5116.8 5138.6 4532.1 4540.2 4551.5 4562.6 4591.8 6074.4 6075.2 6082.7 6086.6 6098.0 6190.3 6324.1 6416.6 6423.3 6425.0 6032.7 6073.7 6088.5 6118.8 6131.2 5596.2 5605.1 5619.3 5623.7 5650.9 6438.3 6619.7 6647.4 6656.7 6666.1 6439.6 6452.4 6455.8 6460.1 6463.1 6607.0 6638.6 6639.0 6647.8 6662.1 6190.8 6256.4 6300.6 6334.8 6360.7 6237.3 6400.8 6458.3 6463.6 6464.3 6311.0 6341.5 6344.6 6352.5 6357.0 6147.4 6179.3 6233.9 6237.1 6323.2 6232.1 6233.3 6241.3 6263.7 6264.4 6265.6 6306.8 6317.9 6334.8 6338.5 6019.0 6119.4 6190.9 6249.3 6250.3 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | [#1] ext.html Cycles:180 Average:42796.63 Median:32978.51 stddev:74352.75 (225.5%) stddev-sans-first:74494.40 23:36:08 INFO - PID 1628 | Values: 777.5 801.9 810.5 1118.3 1132.5 26518.9 36129.5 36499.5 36551.8 36566.5 38561.2 38918.0 38923.6 38993.2 39014.0 6037.7 8270.8 8457.4 8462.1 8483.6 6003.8 6040.9 6074.2 6077.9 6087.8 8279.7 8463.7 8466.2 8466.5 8521.6 8417.8 8487.1 8512.8 8516.1 8525.7 5876.7 5993.2 8255.1 8327.3 8371.8 34970.0 35289.9 35357.0 35404.8 35450.0 3541.6 3565.4 3571.8 3573.9 3578.4 24557.4 30358.0 30374.2 30463.5 30515.5 3032.2 3125.9 3130.9 3145.4 3146.5 229335.2 292825.0 295994.5 296129.0 296343.0 37509.6 47967.8 47969.5 48124.3 48185.0 50083.2 50231.5 50282.5 50323.0 50338.0 13850.6 14003.9 14011.9 14016.4 14032.6 11457.7 11477.1 11492.1 11503.5 11504.1 9497.5 10080.1 10141.7 11731.9 11931.9 5476.8 5523.2 5525.7 5530.0 5532.3 972.9 978.5 979.9 997.5 1014.1 22902.3 23085.4 23282.2 23307.3 23368.8 950.8 1036.5 1077.9 1086.4 1109.6 11695.6 11717.1 11829.4 11858.9 11871.6 298919.0 298948.5 299107.0 299120.0 299608.0 41625.0 51563.5 51663.7 51701.5 51758.7 1448.4 1452.8 1481.0 1491.2 1492.5 22633.0 22668.5 22727.7 22753.8 23013.7 34664.5 34691.7 34719.7 34762.8 34778.8 266526.0 266894.7 267295.0 267354.0 268464.7 32897.5 32938.5 32954.7 32977.2 32979.8 42327.8 43270.0 43442.6 43479.8 43578.6 23165.5 29388.0 29445.1 30188.9 30381.1 29370.6 29454.0 30058.1 30204.5 30378.5 36243.8 36319.6 36327.1 36386.0 36569.5 28759.0 29264.7 29875.3 30706.2 30764.1 23804.8 23827.0 24040.0 24095.0 24168.6 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | [#2] jquery.html Cycles:180 Average:34651.48 Median:12068.51 stddev:91340.90 (756.9%) stddev-sans-first:91560.90 23:36:08 INFO - PID 1628 | Values: 382.8 394.8 417.7 435.2 448.3 11727.2 15205.3 21345.7 21941.7 22013.6 21746.8 21971.5 22040.0 22216.7 22574.3 5646.4 5831.8 5914.6 5993.7 5995.7 3489.5 5883.6 5986.4 6008.6 6013.8 3567.1 3635.3 5946.6 5955.2 5963.0 5683.7 5916.3 5918.3 5922.4 5923.3 5182.8 5289.5 5300.6 5304.1 5304.3 26102.3 26527.5 26757.6 26824.9 26836.6 2163.1 2195.3 2200.9 2201.7 2206.3 16542.9 17011.1 17246.0 17272.3 17422.6 2080.8 2095.0 2138.2 2142.2 2147.1 160870.2 161279.0 163021.0 163349.4 165105.0 9825.5 11753.2 11821.1 11963.2 12115.5 15971.8 16016.8 16018.8 16031.3 16046.4 7652.4 7929.4 8026.3 8608.3 8855.3 8296.9 8304.7 8465.4 8478.4 8500.0 7439.8 7553.5 7625.2 7652.0 7656.2 2108.1 2111.8 2113.2 2116.3 2307.5 776.6 822.5 864.2 909.0 929.5 15440.6 17243.4 18062.2 18660.5 20193.2 875.3 928.3 946.8 952.1 963.1 8872.6 9540.7 10454.9 10496.1 10559.2 518968.0 531644.0 539494.0 550733.0 551644.0 103989.0 106517.4 107196.0 108768.0 124244.0 1133.6 1170.9 1196.3 1196.7 1198.0 18718.4 19376.4 21462.6 21468.2 21517.4 43411.3 54834.2 57756.1 58105.7 58386.0 73064.0 74138.0 75434.2 75458.2 77051.2 12251.6 12261.3 12262.9 12590.9 12623.9 13417.7 13457.3 13708.7 13845.4 13909.9 11571.7 11827.6 11828.3 11837.8 11946.9 10036.9 10915.6 10925.8 11244.0 11283.6 9582.8 11803.5 11851.9 11892.6 12233.4 12292.4 12382.4 12464.1 12736.2 12893.7 11789.8 11853.8 12036.8 12134.5 12262.5 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | [#3] mootools.html Cycles:180 Average:6979.45 Median:3748.90 stddev:4452.32 (118.8%) stddev-sans-first:4444.93 23:36:08 INFO - PID 1628 | Values: 1380.1 1439.4 1458.8 1461.2 1461.4 10919.0 11359.0 11365.1 11365.7 11369.0 11060.7 11562.5 11694.4 11726.9 11771.2 2503.2 2549.2 2554.9 2555.5 2559.3 2071.5 2394.9 2530.4 2530.6 2533.3 2136.0 2321.5 2482.1 2482.5 2486.5 2438.5 2466.5 2474.3 2477.0 2477.7 2478.2 2481.4 2484.4 2487.7 2487.9 11275.4 11540.2 11551.9 11560.9 11573.2 6475.5 6711.2 6719.0 6730.1 6733.5 3655.9 3841.9 4122.9 4243.5 4312.5 1219.6 1224.3 1244.0 1266.6 1269.5 16188.8 16340.4 16361.6 16362.3 16382.0 5251.9 5322.4 5405.5 5526.4 5564.6 11650.1 11710.2 11719.5 11774.4 11781.6 6488.1 6490.4 6503.1 6509.5 6510.3 6039.5 6109.7 6228.6 6233.6 6242.0 4160.5 4263.2 4272.3 4300.2 4310.1 1215.6 1237.5 1251.8 1258.3 1264.2 645.8 652.2 748.1 761.8 766.2 6447.5 6529.5 6571.1 6600.0 6689.7 485.5 585.6 588.3 593.5 692.8 3861.3 4104.1 4110.2 4137.7 4168.9 14482.3 14926.6 15032.5 15060.8 15110.0 14492.9 14839.7 14978.2 14985.3 15282.0 8432.0 8576.6 8633.0 8690.5 8693.2 5886.8 6358.0 6457.3 6501.7 6554.8 10289.7 13002.2 13182.9 13429.8 13548.9 11214.0 13578.9 13711.4 13801.5 14063.5 8960.2 8967.6 9078.1 9360.0 9366.8 10355.7 10501.4 10602.8 10604.3 10668.8 8867.5 8905.0 8925.9 8964.8 9076.2 8728.9 8768.6 8781.2 8827.7 8978.3 6804.9 7920.6 8095.9 8096.8 8096.9 5249.7 5828.9 5920.0 5994.5 6023.7 5112.5 5855.0 6008.6 6028.4 6096.9 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | [#4] prototype.html Cycles:180 Average:5127.29 Median:5570.76 stddev:1512.30 (27.1%) stddev-sans-first:1474.20 23:36:08 INFO - PID 1628 | Values: 393.0 397.4 400.9 405.0 408.4 5938.3 5961.6 5990.2 6005.4 6014.8 4753.6 5384.4 5816.5 5838.4 5892.7 4500.9 4544.6 4560.0 4561.1 4563.6 4541.5 4558.7 4565.1 4569.7 4588.7 4488.6 4519.5 4548.3 4551.2 4561.5 4484.8 4524.8 4527.0 4554.1 4563.6 4042.4 4047.7 4067.0 4067.5 4071.9 5610.9 5762.6 5788.0 5799.3 5799.5 4601.7 5358.0 5443.9 5462.0 5473.5 6023.5 6117.1 6328.3 6334.3 6372.0 4181.7 4317.9 4552.8 4584.9 4599.2 5530.6 6596.4 6616.9 6627.0 6653.5 4111.9 4453.3 4520.2 4690.3 4696.9 3968.6 4661.4 4767.6 4788.2 4821.9 4339.7 5438.7 5452.6 5462.7 5480.6 6253.3 6322.8 6366.8 6385.0 6395.1 1501.3 1623.2 1642.5 1642.6 1654.0 939.6 1081.4 1127.6 1130.0 1136.7 4974.3 5955.3 5955.5 5965.6 5990.0 6365.7 6460.1 6492.7 6502.3 6521.3 4925.2 4930.2 5195.1 5789.3 5883.4 3100.5 3173.9 3366.7 3410.8 3435.3 6109.7 6248.7 6656.9 6711.4 6761.5 5477.0 6521.3 6618.2 6675.8 6754.3 5835.9 6172.4 6284.8 6407.9 6658.6 5528.1 5663.3 5743.7 6484.7 6574.7 5274.0 5302.4 6209.7 6369.5 6426.6 6087.3 6130.4 6683.6 6751.3 6791.8 4449.9 4503.8 4648.7 4672.4 4680.2 5338.0 5740.6 6097.3 6210.0 6514.8 6235.8 6288.3 6296.0 6296.5 6361.3 5153.6 6100.9 6143.5 6189.6 6221.3 5058.9 5119.5 6079.9 6134.7 6175.6 5296.5 5996.6 6066.4 6267.2 6283.9 5368.5 6084.8 6087.5 6126.9 6147.3 23:36:08 INFO - PID 1628 | 23:36:08 INFO - PID 1628 | [#5] yui.html Cycles:180 Average:21164.77 Median:21332.29 stddev:30918.34 (144.9%) stddev-sans-first:30966.91 23:36:08 INFO - PID 1628 | Values: 708.0 852.9 853.2 857.7 874.6 13364.7 18300.8 18409.6 18953.4 18981.1 17170.9 17453.6 17551.4 17562.0 17602.5 939.6 970.4 972.6 973.8 977.0 655.3 659.1 674.9 986.9 998.9 744.0 745.6 907.1 987.0 1003.8 979.2 981.7 982.7 982.7 1016.4 729.4 729.8 730.3 739.8 756.6 15992.4 16236.1 16530.5 16987.4 17244.8 16737.6 16782.4 17109.5 17198.4 17368.3 13724.9 13890.1 14084.4 14087.7 14110.4 3393.4 3420.0 3420.5 3422.0 3428.0 123306.0 123791.0 125351.4 126361.0 127252.4 11981.5 12054.0 12091.7 12208.6 12213.9 20897.5 21048.0 21093.8 21255.8 21408.8 7551.5 7627.8 7632.4 7632.8 7652.0 3731.2 3794.5 3830.5 3858.7 3865.9 4153.0 4186.5 4231.7 4235.2 4241.8 858.3 876.5 877.5 879.2 880.2 652.7 668.8 675.6 677.5 684.2 10577.3 11394.9 11860.5 11896.3 12056.8 627.7 661.4 667.1 674.7 694.7 5393.0 5577.2 5611.2 5694.0 5719.2 107345.0 108245.0 108367.0 110560.0 111116.0 89149.0 95871.0 97102.5 97525.5 98566.0 55294.0 67804.3 78449.6 80500.0 80712.6 10641.8 10911.8 11081.3 11171.8 11209.9 40610.0 40742.8 41125.6 41630.0 42313.5 52572.0 53194.7 53428.5 53521.2 55674.2 12085.3 14299.6 14483.0 14647.3 14795.0 15465.5 15478.3 15715.2 15918.7 15956.2 14458.5 14721.7 14802.6 14900.5 14905.8 14331.6 14804.8 14893.6 15013.5 15068.8 11657.2 11989.5 12105.3 12122.9 12129.1 10360.4 10401.6 10598.1 10998.6 11005.1 11382.4 11577.9 11939.2 12141.8 12199.7 23:36:08 INFO - PID 1628 | -------- Summary: end -------- 23:36:08 INFO - PID 1628 | 23:36:09 INFO - TEST-INFO | 1628: exit 0 23:36:09 INFO - TEST-OK | dromaeo_css | took 1117990ms 23:36:09 INFO - TEST-START | kraken 23:36:09 INFO - operating with platform_type : mac_ 23:36:09 INFO - Initialising browser for kraken test... 23:36:09 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpPtnnFC/profile http://localhost:49233/getInfo.html) 23:36:11 INFO - PID 1639 | __metrics Screen width/height:1600/1200 23:36:11 INFO - PID 1639 | colorDepth:24 23:36:11 INFO - PID 1639 | Browser inner width/height: 1024/689 23:36:11 INFO - PID 1639 | __metrics 23:36:12 INFO - PID 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:36:12 INFO - PID 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:36:13 INFO - TEST-INFO | 1639: exit 0 23:36:13 INFO - Browser initialized. 23:36:13 INFO - Running cycle 1/1 for kraken test... 23:36:13 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpPtnnFC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:36:25 INFO - PID 1640 | RSS: Main: 239792128 23:36:25 INFO - PID 1640 | 23:36:28 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 23:36:28 INFO - PID 1640 | RSS: Main: 312217600 23:36:28 INFO - PID 1640 | 23:36:31 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 23:36:32 INFO - PID 1640 | RSS: Main: 295161856 23:36:32 INFO - PID 1640 | 23:36:35 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 23:36:35 INFO - PID 1640 | RSS: Main: 386129920 23:36:35 INFO - PID 1640 | 23:36:37 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 23:36:38 INFO - PID 1640 | RSS: Main: 288292864 23:36:38 INFO - PID 1640 | 23:36:40 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:36:41 INFO - PID 1640 | RSS: Main: 291893248 23:36:41 INFO - PID 1640 | 23:36:43 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 23:36:44 INFO - PID 1640 | RSS: Main: 355291136 23:36:44 INFO - PID 1640 | 23:36:47 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 23:36:48 INFO - PID 1640 | RSS: Main: 372486144 23:36:48 INFO - PID 1640 | 23:36:50 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 23:36:51 INFO - PID 1640 | RSS: Main: 379502592 23:36:51 INFO - PID 1640 | 23:36:52 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:36:53 INFO - PID 1640 | RSS: Main: 333021184 23:36:53 INFO - PID 1640 | 23:36:55 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:36:56 INFO - PID 1640 | RSS: Main: 330285056 23:36:56 INFO - PID 1640 | 23:36:58 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:36:58 INFO - PID 1640 | RSS: Main: 333463552 23:36:58 INFO - PID 1640 | 23:37:01 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:37:02 INFO - PID 1640 | RSS: Main: 352874496 23:37:02 INFO - PID 1640 | 23:37:05 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:37:06 INFO - PID 1640 | RSS: Main: 345935872 23:37:06 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 23:37:07 INFO - PID 1640 | RSS: Main: 388059136 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | __start_tp_report 23:37:07 INFO - PID 1640 | _x_x_mozilla_page_load 23:37:07 INFO - PID 1640 | _x_x_mozilla_page_load_details 23:37:07 INFO - PID 1640 | |i|pagename|runs| 23:37:07 INFO - PID 1640 | |0;ai-astar;112;111;111;110;111;111;111;111;109;109 23:37:07 INFO - PID 1640 | |1;audio-beat-detection;142;111;107;147;110;104;108;103;133;107 23:37:07 INFO - PID 1640 | |2;audio-dft;186;186;180;185;186;183;184;183;184;183 23:37:07 INFO - PID 1640 | |3;audio-fft;81;58;60;85;61;60;62;57;86;75 23:37:07 INFO - PID 1640 | |4;audio-oscillator;84;69;84;70;68;68;89;69;67;97 23:37:07 INFO - PID 1640 | |5;imaging-gaussian-blur;87;86;86;87;88;86;85;86;87;87 23:37:07 INFO - PID 1640 | |6;imaging-darkroom;94;93;94;94;94;94;95;94;95;95 23:37:07 INFO - PID 1640 | |7;imaging-desaturate;85;85;86;87;85;85;83;84;87;85 23:37:07 INFO - PID 1640 | |8;json-parse-financial;50;49;51;50;52;50;51;50;48;50 23:37:07 INFO - PID 1640 | |9;json-stringify-tinderbox;55;53;61;60;67;60;58;59;59;57 23:37:07 INFO - PID 1640 | |10;stanford-crypto-aes;84;84;83;82;82;82;83;82;81;81 23:37:07 INFO - PID 1640 | |11;stanford-crypto-ccm;137;139;137;137;136;135;145;135;176;149 23:37:07 INFO - PID 1640 | |12;stanford-crypto-pbkdf2;192;187;181;186;182;183;186;184;181;184 23:37:07 INFO - PID 1640 | |13;stanford-crypto-sha256-iterative;68;66;67;67;67;69;66;67;67;67 23:37:07 INFO - PID 1640 | __end_tp_report 23:37:07 INFO - PID 1640 | __start_cc_report 23:37:07 INFO - PID 1640 | _x_x_mozilla_cycle_collect,2131 23:37:07 INFO - PID 1640 | __end_cc_report 23:37:07 INFO - PID 1640 | __startTimestamp1493447827970__endTimestamp 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | ------- Summary: start ------- 23:37:07 INFO - PID 1640 | Number of tests: 14 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:0.97 (0.9%) stddev-sans-first:0.88 23:37:07 INFO - PID 1640 | Values: 112.0 111.0 111.0 110.0 111.0 111.0 111.0 111.0 109.0 109.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#1] audio-beat-detection Cycles:10 Average:117.20 Median:110.50 stddev:16.71 (15.1%) stddev-sans-first:15.12 23:37:07 INFO - PID 1640 | Values: 142.0 111.0 107.0 147.0 110.0 104.0 108.0 103.0 133.0 107.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.89 (1.0%) stddev-sans-first:1.86 23:37:07 INFO - PID 1640 | Values: 186.0 186.0 180.0 185.0 186.0 183.0 184.0 183.0 184.0 183.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#3] audio-fft Cycles:10 Average:68.50 Median:68.50 stddev:11.84 (17.3%) stddev-sans-first:11.67 23:37:07 INFO - PID 1640 | Values: 81.0 58.0 60.0 85.0 61.0 60.0 62.0 57.0 86.0 75.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.00 stddev:10.95 (14.2%) stddev-sans-first:11.27 23:37:07 INFO - PID 1640 | Values: 84.0 69.0 84.0 70.0 68.0 68.0 89.0 69.0 67.0 97.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 23:37:07 INFO - PID 1640 | Values: 87.0 86.0 86.0 87.0 88.0 86.0 85.0 86.0 87.0 87.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 23:37:07 INFO - PID 1640 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 23:37:07 INFO - PID 1640 | Values: 85.0 85.0 86.0 87.0 85.0 85.0 83.0 84.0 87.0 85.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:1.10 (2.2%) stddev-sans-first:1.17 23:37:07 INFO - PID 1640 | Values: 50.0 49.0 51.0 50.0 52.0 50.0 51.0 50.0 48.0 50.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.50 stddev:3.75 (6.3%) stddev-sans-first:3.71 23:37:07 INFO - PID 1640 | Values: 55.0 53.0 61.0 60.0 67.0 60.0 58.0 59.0 59.0 57.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:1.07 (1.3%) stddev-sans-first:0.97 23:37:07 INFO - PID 1640 | Values: 84.0 84.0 83.0 82.0 82.0 82.0 83.0 82.0 81.0 81.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:138.00 stddev:12.60 (9.1%) stddev-sans-first:13.20 23:37:07 INFO - PID 1640 | Values: 137.0 139.0 137.0 137.0 136.0 135.0 145.0 135.0 176.0 149.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:185.00 stddev:3.34 (1.8%) stddev-sans-first:2.22 23:37:07 INFO - PID 1640 | Values: 192.0 187.0 181.0 186.0 182.0 183.0 186.0 184.0 181.0 184.0 23:37:07 INFO - PID 1640 | 23:37:07 INFO - PID 1640 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 23:37:07 INFO - PID 1640 | Values: 68.0 66.0 67.0 67.0 67.0 69.0 66.0 67.0 67.0 67.0 23:37:07 INFO - PID 1640 | -------- Summary: end -------- 23:37:07 INFO - PID 1640 | 23:37:09 INFO - TEST-INFO | 1640: exit 0 23:37:09 INFO - TEST-OK | kraken | took 60009ms 23:37:09 INFO - SUITE-END | took 1177s 23:37:09 INFO - Completed test suite (00:19:38) 23:37:09 INFO - javascript benchmark 23:37:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7590.693350453677, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2827.0, 3419.640937701541, 3422.828858557073, 3445.3626079175338, 3467.75725699201, 5676.063449530822, 5823.06830907055, 6019.608725432844, 6042.818715438539, 6045.244344456943, 5705.315362156547, 5716.971415142929, 5717.742701578429, 5729.799457019005, 5736.942630573693, 5049.67177133487, 5073.264376665391, 5099.388073431193, 5111.565516931066, 5115.769790359424, 5185.974070129644, 5192.169252919533, 5197.688138711689, 5210.322658054448, 5212.556932660709, 5198.6880787152895, 5199.532042116209, 5204.9739751301195, 5214.556762675177, 5222.190560463135, 5171.250168725552, 5197.038547868638, 5200.0, 5200.32395788547, 5200.531952124327, 5069.391672999227, 5138.126518491866, 5157.8452646420665, 5163.63854530183, 5216.634835561512, 5957.076653118774, 6018.729157187937, 6044.395560443921, 6080.695965201722, 6089.238845144357, 6241.188645476104, 6339.080833279195, 6341.270753863272, 6342.429181373698, 6349.555531112824, 5811.4479124483105, 6331.398517140864, 6339.302676705559, 6361.554691171574, 6427.903581446282, 6075.27096748393, 6091.0863370494335, 6094.116353128771, 6107.969460152715, 6110.725017374273, 6533.640649764308, 6569.638669873203, 6575.671216439159, 6589.308122647149, 6589.835254118685, 6175.475084617858, 6303.054541818672, 6310.337414571404, 6333.20834895638, 6336.17629708135, 6257.561970662101, 6287.2140982377205, 6307.117003619497, 6380.361963803675, 6382.521310901608, 6339.175907132043, 6348.746050157942, 6350.8412289692205, 6354.745810167634, 6355.491560675135, 5023.447420783711, 6270.122182894398, 6320.873582528324, 6339.492840572743, 6366.936330636727, 4963.478834722375, 5052.0, 5074.619403544749, 5116.8209112681625, 5138.58891288696, 4532.116237333688, 4540.205464043779, 4551.544845515422, 4562.61217796491, 4591.816327346935, 6074.42292982166, 6075.2405949256345, 6082.726277317532, 6086.573939824259, 6097.969510152421, 6190.288116866528, 6324.114623952651, 6416.615003099829, 6423.261324947597, 6424.967875160594, 6032.668203248863, 6073.6659483727935, 6088.51291896656, 6118.847028824226, 6131.202943617302, 5596.188552659842, 5605.103183490622, 5619.297587801525, 5623.6906970116215, 5650.915236271459, 6438.259600146043, 6619.735210591522, 6647.368499992493, 6656.667166641745, 6666.133402657721, 6439.613623182624, 6452.387023232785, 6455.806325810233, 6460.127882735958, 6463.062855885964, 6607.0, 6638.568493048129, 6639.0, 6647.800565983028, 6662.133922590032, 6190.845228869314, 6256.405641464054, 6300.621962682254, 6334.809955701337, 6360.650164241011, 6237.251529816451, 6400.807975760735, 6458.321876203119, 6463.612183269019, 6464.256610489854, 6311.0, 6341.524385671001, 6344.555881088464, 6352.460040896575, 6357.046443033582, 6147.354527774698, 6179.28938172098, 6233.875322493615, 6237.064440333987, 6323.241211054703, 6232.127502149793, 6233.314335423009, 6241.251049874044, 6263.655498947602, 6264.373562643699, 6265.631694849344, 6306.779262725782, 6317.905231421624, 6334.778282760081, 6338.524610654127, 6019.0, 6119.41115851624, 6190.876182476235, 6249.281332646622, 6250.312465628686], "value": 5846.340175160953, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.4752042371398, 801.9117897031327, 810.4731924249237, 1118.3010618363523, 1132.5413207650904, 26518.867405662968, 36129.45805812915, 36499.452508212366, 36551.817240913755, 36566.45150322747, 38561.228775424475, 38918.0, 38923.61076389242, 38993.22013559727, 39014.02464938378, 6037.698115094249, 8270.793230169234, 8457.365697572675, 8462.069172391031, 8483.618237179311, 6003.849903752419, 6040.939590604104, 6074.179985701918, 6077.878442431149, 6087.756489740427, 8279.668813247494, 8463.703770368038, 8466.153384661546, 8466.534340611264, 8521.573921303941, 8417.83164336713, 8487.066422693499, 8512.787180320542, 8516.148385161465, 8525.65897364111, 5876.7355469004, 5993.190919225893, 8255.133211012817, 8327.292180164693, 8371.832563348698, 34970.0, 35289.94130176099, 35357.0, 35404.82297588521, 35450.0, 3541.557305336833, 3565.358235517606, 3571.785692858437, 3573.928521429583, 3578.4453409721536, 24557.38606534851, 30358.0, 30374.240643983856, 30463.543046854316, 30515.542266866014, 3032.181311046027, 3125.9062228133193, 3130.9217269568217, 3145.433821912055, 3146.480830662938, 229335.1864257636, 292825.0, 295994.52002740063, 296129.0, 296343.0, 37509.62490375116, 47967.760161198974, 47969.5203047972, 48124.27813582799, 48185.0, 50083.24875126876, 50231.49768502268, 50282.49717502851, 50323.0, 50338.0, 13850.584482465541, 14003.929980350033, 14011.859881401055, 14016.439342426393, 14032.649183770283, 11457.713557161138, 11477.081833453303, 11492.138089643311, 11503.539858405737, 11504.079673626089, 9497.477638729799, 10080.14318782897, 10141.746456338504, 11731.88268117325, 11931.940340298417, 5476.835694929158, 5523.238380809596, 5525.696086715269, 5530.0, 5532.336119665666, 972.8978457261885, 978.4667356290838, 979.8579206015088, 997.5261750668519, 1014.0873214107215, 22902.312930612108, 23085.422864428187, 23282.185123520594, 23307.302669841258, 23368.766312337, 950.7813202963357, 1036.549101141006, 1077.8706555213425, 1086.3970496374588, 1109.6449136276476, 11695.649130525928, 11717.12121590884, 11829.408529573659, 11858.88141118595, 11871.584494542649, 298919.0, 298948.50525747234, 299107.0, 299120.0, 299608.0, 41625.0, 51563.48436515662, 51663.74168129211, 51701.48298517042, 51758.706032349495, 1448.3554818106052, 1452.7602945514084, 1481.0299253988655, 1491.1723993183887, 1492.4925525321444, 22633.0, 22668.546629066997, 22727.659085114068, 22753.77246227583, 23013.734244616706, 34664.480032800035, 34691.653083468846, 34719.65280347164, 34762.82618586891, 34778.82610586931, 266526.0, 266894.6655266711, 267295.0, 267354.0, 268464.65767671034, 32897.506537402434, 32938.50592241166, 32954.67045329516, 32977.18625475526, 32979.83510082434, 42327.788361057996, 43270.0, 43442.565574343855, 43479.782601086794, 43578.56421435745, 23165.536689265784, 29388.0, 29445.055494450382, 30188.94338698135, 30381.088567343017, 29370.559441607966, 29454.0, 30058.098257052323, 30204.5469317956, 30378.544321834746, 36243.81878090592, 36319.63680363163, 36327.09182270464, 36386.0, 36569.45145822762, 28758.993435229666, 29264.707352926198, 29875.253118672208, 30706.23234419068, 30764.077077687703, 23804.761952380253, 23827.0, 24040.0, 24095.0, 24168.637470437607], "value": 16648.766078458004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.7779887665154, 394.8124640795624, 417.6804744370555, 435.19271750902084, 448.3073651208882, 11727.237729547584, 15205.315760790763, 21345.679814802752, 21941.670874936888, 22013.559728805434, 21746.782532174675, 21971.450713732163, 22040.008199631015, 22216.666749998723, 22574.322770316874, 5646.378898321182, 5831.842218379195, 5914.585978981473, 5993.700314984255, 5995.700214989255, 3489.459133834247, 5883.646981181121, 5986.401359864, 6008.639481631094, 6013.849653758647, 3567.0547304964207, 3635.345917607482, 5946.643201407929, 5955.196048533436, 5963.0459126539545, 5683.715814209314, 5916.2900451945825, 5918.348981612041, 5922.3781502942, 5923.318818335903, 5182.844514664566, 5289.523942845124, 5300.628955973045, 5304.124819404794, 5304.336957880265, 26102.34744131393, 26527.46945061088, 26757.598636020477, 26824.865875670497, 26836.597451038055, 2163.0915015693445, 2195.3413975807352, 2200.8899555022344, 2201.7027701260286, 2206.3050139206257, 16542.917285413616, 17011.14944252795, 17245.965242085516, 17272.30910763568, 17422.56443588908, 2080.843936704753, 2095.0, 2138.193226723416, 2142.2180903970093, 2147.1304121830685, 160870.19564902218, 161279.0, 163021.0, 163349.3665063346, 165104.9999999988, 9825.508724563886, 11753.236039657395, 11821.113416493798, 11963.222390544586, 12115.454804533818, 15971.760423593656, 16016.839831601768, 16018.839811601967, 16031.278592463366, 16046.358145674043, 7652.426068044919, 7929.365650748042, 8026.277635012877, 8608.34145488165, 8855.33584981129, 8296.917030829734, 8304.70933517336, 8465.365097617703, 8478.406511544255, 8499.980002399752, 7439.776806695808, 7553.489302139594, 7625.161232264447, 7652.020949106433, 7656.196099409593, 2108.0619124489763, 2111.7571479279773, 2113.2075471698113, 2116.322776711468, 2307.4692820651007, 776.5728849132954, 822.4982760516081, 864.1919804982361, 908.9909100909039, 929.5073610986053, 15440.613984650248, 17243.3964811231, 18062.18720157596, 18660.53348666267, 20193.192272308945, 875.3085062800444, 928.2620316848122, 946.8437707778277, 952.1480719999535, 963.0754475703263, 8872.600732967032, 9540.713778586654, 10454.947725261325, 10496.055355018085, 10559.208059395576, 518968.0, 531644.0, 539494.0, 550733.0, 551644.0, 103989.0, 106517.40223896648, 107196.0, 108768.0, 124244.0, 1133.5749094089717, 1170.9180357374903, 1196.2642974570751, 1196.7008247938015, 1197.9760404791855, 18718.438446846616, 19376.35300999431, 21462.570748585254, 21468.248611298528, 21517.354479365644, 43411.34882976821, 54834.2142722059, 57756.13365799432, 58105.70947145237, 58386.0, 73064.0, 74138.0, 75434.24565754272, 75458.24541754732, 77051.2294877044, 12251.632451026484, 12261.325627090595, 12262.938685306517, 12590.874091258971, 12623.87376126227, 13417.675082664484, 13457.259850707913, 13708.65728356799, 13845.446182152602, 13909.930450348089, 11571.710707232387, 11827.645170644895, 11828.290302581872, 11837.76324473523, 11946.880531194924, 10036.89963100389, 10915.563377464812, 10925.836112458479, 11244.0, 11283.605073822377, 9582.808343833223, 11803.468843901874, 11851.9333260007, 11892.643220703392, 12233.388330583613, 12292.38538073075, 12382.442790074649, 12464.127511074137, 12736.23582585048, 12893.742125157256, 11789.764204715686, 11853.76292474163, 12036.75926481483, 12134.453949572115, 12262.509499620272], "value": 10184.86975709637, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.116725295811, 1439.366678661389, 1458.766597344425, 1461.189040082755, 1461.371610207611, 10919.0, 11358.977692007727, 11365.090792736582, 11365.659030229106, 11369.0, 11060.723481912935, 11562.53749850007, 11694.415279236047, 11726.882731172685, 11771.176017678767, 2503.2490252924094, 2549.247971848309, 2554.9397000244967, 2555.514452254066, 2559.2706078767555, 2071.4510654676524, 2394.928152155438, 2530.430653103046, 2530.607755797854, 2533.290678609992, 2136.0494579911947, 2321.466062805556, 2482.056818409002, 2482.466269752003, 2486.490269494758, 2438.5244877248942, 2466.4943686544216, 2474.3195621204222, 2477.0, 2477.690288713911, 2478.21936090131, 2481.4168670362633, 2484.441000774811, 2487.7139129000216, 2487.8631675257852, 11275.379854108021, 11540.192186546945, 11551.942240288829, 11560.884391156149, 11573.247738896946, 6475.51433642474, 6711.161104861892, 6718.966405167992, 6730.091437655951, 6733.528652994243, 3655.9086022849374, 3841.8847434577006, 4122.896927576805, 4243.469566304212, 4312.460942382202, 1219.5548624751902, 1224.3327386574338, 1243.9689007774787, 1266.639007882749, 1269.479513399511, 16188.75716864248, 16340.428085016967, 16361.590960226089, 16362.345506179738, 16382.0170789753, 5251.894962100813, 5322.426802898417, 5405.5135037846785, 5526.392096869341, 5564.638298510584, 11650.126240531994, 11710.238834475902, 11719.472623731952, 11774.411279435993, 11781.646550603497, 6488.124103246096, 6490.350964903472, 6503.057056726842, 6509.479241660656, 6510.316416776265, 6039.516838652898, 6109.725062372204, 6228.626282423069, 6233.625982441068, 6241.96879015602, 4160.521540022936, 4263.186297043936, 4272.273713468718, 4300.204462174508, 4310.094880075157, 1215.604928398274, 1237.4740735187509, 1251.8310028146266, 1258.3079306381453, 1264.165650670553, 645.8012995387115, 652.2010537092025, 748.0686545251197, 761.8438220164853, 766.2452484302958, 6447.484201263887, 6529.510286728514, 6571.1289758825205, 6599.967000164968, 6689.732410703517, 485.4926601701231, 585.5520526797004, 588.2646691635456, 593.4955288005161, 692.7506097804786, 3861.304965106308, 4104.056067104582, 4110.177964407071, 4137.710360274812, 4168.854090106832, 14482.275886205647, 14926.626834329229, 15032.473863414729, 15060.774088388684, 15110.01784883979, 14492.855071449361, 14839.70320593582, 14978.17620030887, 14985.250737463084, 15282.006669566443, 8432.0, 8576.614052367659, 8632.964044314722, 8690.522021288763, 8693.174148455886, 5886.793962211302, 6357.96821015892, 6457.257415397289, 6501.739930402731, 6554.80335589933, 10289.74275643115, 13002.154859934075, 13182.868171318163, 13429.798553021908, 13548.932255338661, 11214.0, 13578.864211358155, 13711.382987765774, 13801.480622441615, 14063.507777227747, 8960.238379737795, 8967.551622418983, 9078.09219078087, 9359.953200233955, 9366.829146356706, 10355.741106472398, 10501.42242176657, 10602.840957385491, 10604.257701961098, 10668.786624267626, 8867.512286824287, 8904.97592776813, 8925.910740892508, 8964.820703586021, 9076.228520575825, 8728.91271087281, 8768.561571921506, 8781.20969112783, 8827.69103081389, 8978.281737461119, 6804.863902721819, 7920.643571039202, 8095.87856182146, 8096.797580060545, 8096.878546821685, 5249.685018898878, 5828.94171058284, 5920.0, 5994.490468310242, 6023.698815059317, 5112.488751124858, 5855.0, 6008.609440386361, 6028.3971602839365, 6096.939030609815], "value": 5215.718691910184, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.96070392960706, 397.3642172523962, 400.8551576696953, 405.0259126800047, 408.37722473228325, 5938.287405511339, 5961.552883533742, 5990.221271234745, 6005.4294841989995, 6014.759409623615, 4753.572178503934, 5384.3538775346915, 5816.534677225823, 5838.357780644125, 5892.675902825343, 4500.9041771987495, 4544.613892762706, 4559.954400456003, 4561.133384656921, 4563.58528856054, 4541.458541458542, 4558.703684260529, 4565.1098035883015, 4569.748663823489, 4588.655850811202, 4488.573585509372, 4519.508562174475, 4548.272276435771, 4551.249043907751, 4561.521040290771, 4484.8206071757095, 4524.796384162721, 4526.977365113154, 4554.064522577891, 4563.566461186182, 4042.353223484258, 4047.7249666355297, 4067.0, 4067.5118985721606, 4071.9389209161886, 5610.887782244332, 5762.567807414461, 5787.971060144714, 5799.333076696192, 5799.507041901444, 4601.722147536957, 5358.035553600351, 5443.891122177534, 5461.972690136564, 5473.480019398191, 6023.518118550505, 6117.113018612321, 6328.335524769925, 6334.303226645112, 6372.0, 4181.686373521968, 4317.935230971538, 4552.772361381951, 4584.885377865547, 4599.241125214336, 5530.640508366943, 6596.406323430914, 6616.93383066168, 6626.966865165643, 6653.500987425962, 4111.876643700693, 4453.265211240174, 4520.16933142078, 4690.296455531698, 4696.896229386091, 3968.5634580196156, 4661.417322834645, 4767.594754445911, 4788.209945359046, 4821.927671084936, 4339.696221264481, 5438.700871452029, 5452.645578037414, 5462.699551524624, 5480.6437581557075, 6253.280872699698, 6322.778702745381, 6366.78477368935, 6385.010323399881, 6395.072714456378, 1501.2943916359293, 1623.1802939515705, 1642.5072478256475, 1642.5647203490846, 1653.9586510337097, 939.6476321379482, 1081.4268437728017, 1127.639155470258, 1129.9604513842141, 1136.6703655939684, 4974.328465657162, 5955.302738719444, 5955.52355811534, 5965.612235204698, 5990.0, 6365.708344995798, 6460.127882735865, 6492.707828147744, 6502.284748677642, 6521.271862956321, 4925.236588328815, 4930.161872481687, 5195.090859099642, 5789.2763404574425, 5883.382244864313, 3100.534919762054, 3173.8574113319187, 3366.7474939379645, 3410.761246712756, 3435.3301106284534, 6109.6639684817765, 6248.656323902138, 6656.933430665728, 6711.395974362331, 6761.4893861502715, 5476.986757449885, 6521.347865213535, 6618.172728408949, 6675.766348177887, 6754.257031726506, 5835.883282334415, 6172.4136207060255, 6284.842878927972, 6407.942689456281, 6658.600483970978, 5528.087865502147, 5663.32040155184, 5743.655380677173, 6484.740610375532, 6574.704138313692, 5273.973630131824, 5302.416734159162, 6209.658468784261, 6369.522285828489, 6426.614403135827, 6087.269527656709, 6130.356312587114, 6683.6324002180345, 6751.324867513209, 6791.830204244933, 4449.888752781207, 4503.842365517191, 4648.721076735406, 4672.439307283148, 4680.157571637138, 5338.0, 5740.626859254302, 6097.329293777592, 6210.006398976142, 6514.837129071621, 6235.812925612239, 6288.308286088435, 6295.968520157553, 6296.496280297657, 6361.268454127835, 5153.639245252946, 6100.938990610037, 6143.477804386498, 6189.6286222826775, 6221.253449586078, 5058.9494105058475, 5119.462456442169, 6079.878402432015, 6134.723937422916, 6175.560653541736, 5296.470352964827, 5996.640201587918, 6066.3630318815885, 6267.185265915402, 6283.900317444521, 5368.489993450616, 6084.787032453843, 6087.4521293083835, 6126.855600539775, 6147.293061298008], "value": 4668.9488554196105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.9610621415826, 852.9402941794074, 853.1766844994579, 857.7383897911138, 874.5627186406795, 13364.732705345887, 18300.816991830077, 18409.63180736386, 18953.43139705811, 18981.14584843682, 17170.914145429288, 17453.56366090845, 17551.38570150045, 17562.034088125147, 17602.471925842245, 939.5912777941583, 970.3886551472598, 972.5866506734609, 973.7711637765119, 976.9853452198187, 655.3217419970332, 659.0707102984792, 674.9358810912955, 986.8865080515761, 998.9400635961829, 744.0178963767829, 745.619733935694, 907.1155623267327, 986.9506524673774, 1003.8494225866142, 979.1774909076339, 981.7398389426808, 982.6609819612222, 982.7395740128871, 1016.374929418406, 729.4492658043197, 729.8248420379124, 730.3317464519957, 739.8298391370015, 756.5687558091889, 15992.36030558788, 16236.107014114219, 16530.504084877473, 16987.40544080951, 17244.827551724575, 16737.581560461087, 16782.412615558398, 17109.486715398558, 17198.3980560681, 17368.305267789274, 13724.862751372459, 13890.097143685629, 14084.436622535086, 14087.718245635133, 14110.435582576685, 3393.4061539230784, 3419.982900085484, 3420.504026916108, 3421.9657803421896, 3428.0401487583517, 123306.0, 123791.0, 125351.37324313412, 126361.0, 127252.36373818164, 11981.460834262305, 12054.0, 12091.697707557205, 12208.633740987785, 12213.877861221452, 20897.47756306074, 21048.0, 21093.78906210949, 21255.78744212538, 21408.78591214068, 7551.509151905108, 7627.809304767426, 7632.427567932427, 7632.847343053108, 7652.0, 3731.1791405890663, 3794.5067141271456, 3830.502034735522, 3858.6720128789366, 3865.942010869839, 4153.0447996960265, 4186.455760751083, 4231.661467082626, 4235.152969406131, 4241.830326786956, 858.2961971183694, 876.4609764994483, 877.4559772940818, 879.2438502887512, 880.1858281089966, 652.7160685101901, 668.8327918020495, 675.5743881354715, 677.5291172634971, 684.2473279392727, 10577.31247468912, 11394.886051139549, 11860.525578976916, 11896.286222826659, 12056.819147712791, 627.7175271128011, 661.441082285468, 667.0594461808923, 674.6828990374516, 694.7221111555317, 5393.0, 5577.247071645357, 5611.2144299798065, 5694.0, 5719.170720245541, 107345.0, 108245.0, 108367.0, 110560.0, 111116.0, 89149.0, 95871.0, 97102.5144874271, 97525.5123724391, 98566.0, 55294.0, 67804.32195677981, 78449.60775196088, 80499.95000500021, 80712.59643701861, 10641.787164256517, 10911.836322455329, 11081.33511989251, 11171.832422513506, 11209.943950280196, 40609.984750381474, 40742.79628601838, 41125.588744112174, 41630.0, 42313.519026833914, 52572.0, 53194.73402632962, 53428.46571534235, 53521.19718204152, 55674.16488752753, 12085.335306558223, 14299.57101286963, 14482.98619096653, 14647.267636617913, 14795.0, 15465.458708945132, 15478.303476343366, 15715.214239287761, 15918.68162636764, 15956.202189890691, 14458.493952711604, 14721.705565888407, 14802.555923322318, 14900.478483253035, 14905.776413354026, 14331.641708957359, 14804.7779283313, 14893.553193404216, 15013.549593512213, 15068.773968390264, 11657.242279251821, 11989.520419183134, 12105.273683579013, 12122.878771212176, 12129.090318226345, 10360.430176340373, 10401.636481564696, 10598.145324568015, 10998.615048473264, 11005.119590432587, 11382.430878456209, 11577.942110289394, 11939.164258501822, 12141.757164856477, 12199.695007624525], "value": 7923.243035783162, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1408.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 111.0, 110.0, 111.0, 111.0, 111.0, 111.0, 109.0, 109.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 107.0, 147.0, 110.0, 104.0, 108.0, 103.0, 133.0, 107.0], "value": 117.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 180.0, 185.0, 186.0, 183.0, 184.0, 183.0, 184.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 85.0, 61.0, 60.0, 62.0, 57.0, 86.0, 75.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 84.0, 70.0, 68.0, 68.0, 89.0, 69.0, 67.0, 97.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 87.0, 88.0, 86.0, 85.0, 86.0, 87.0, 87.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 87.0, 85.0, 85.0, 83.0, 84.0, 87.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 50.0, 52.0, 50.0, 51.0, 50.0, 48.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 61.0, 60.0, 67.0, 60.0, 58.0, 59.0, 59.0, 57.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 82.0, 82.0, 82.0, 83.0, 82.0, 81.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 137.0, 137.0, 136.0, 135.0, 145.0, 135.0, 176.0, 149.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 187.0, 181.0, 186.0, 182.0, 183.0, 186.0, 184.0, 181.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 67.0, 67.0, 69.0, 66.0, 67.0, 67.0, 67.0], "value": 67.1, "unit": "score"}]}]} 23:37:09 INFO - Return code: 0 23:37:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:37:09 ERROR - # TBPL SUCCESS # 23:37:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:37:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:37:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:37:09 INFO - 2017-04-28 23:37:09.778 screenresolution[1645:9926] starting screenresolution argv=screenresolution get 23:37:09 INFO - 2017-04-28 23:37:09.781 screenresolution[1645:9926] Display 0: 1600x1200x32@60 23:37:09 INFO - 2017-04-28 23:37:09.787 screenresolution[1646:9929] starting screenresolution argv=screenresolution list 23:37:09 INFO - Available Modes on Display 0 23:37:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:37:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:37:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:37:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:37:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:37:10 INFO - Intel Iris: 23:37:10 INFO - Chipset Model: Intel Iris 23:37:10 INFO - Type: GPU 23:37:10 INFO - Bus: Built-In 23:37:10 INFO - VRAM (Dynamic, Max): 1536 MB 23:37:10 INFO - Vendor: Intel (0x8086) 23:37:10 INFO - Device ID: 0x0a2e 23:37:10 INFO - Revision ID: 0x0009 23:37:10 INFO - Displays: 23:37:10 INFO - Display: 23:37:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:37:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:37:10 INFO - Display Serial Number: 1600x1200 60 23:37:10 INFO - Main Display: Yes 23:37:10 INFO - Mirror: Off 23:37:10 INFO - Online: Yes 23:37:10 INFO - Rotation: Supported 23:37:10 INFO - Return code: 0 23:37:10 INFO - Running post-action listener: _resource_record_post_action 23:37:10 INFO - [mozharness: 2017-04-29 06:37:10.028669Z] Finished run-tests step (success) 23:37:10 INFO - Running post-run listener: _resource_record_post_run 23:37:10 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 39183872; Write bytes: 509600768; Read time: 413; Write time: 1545 23:37:10 INFO - TinderboxPrint: CPU usage
53.3% 23:37:10 INFO - TinderboxPrint: I/O read bytes / time
39,183,872 / 413 23:37:10 INFO - TinderboxPrint: I/O write bytes / time
509,600,768 / 1,545 23:37:10 INFO - TinderboxPrint: CPU idle
2,239.9 (46.7%) 23:37:10 INFO - TinderboxPrint: CPU system
953.0 (19.9%) 23:37:10 INFO - TinderboxPrint: CPU user
1,604.6 (33.4%) 23:37:10 INFO - TinderboxPrint: Swap in / out
247,062,528 / 0 23:37:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208181760; Write bytes: 206013952; Read time: 16280; Write time: 431 23:37:10 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 34432512; Write bytes: 296349184; Read time: 351; Write time: 1084 23:37:10 INFO - Running post-run listener: _upload_blobber_files 23:37:10 INFO - Blob upload gear active. 23:37:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:37:10 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 23:37:10 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:37:10 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:37:10 INFO - (blobuploader) - INFO - Open directory for files ... 23:37:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:37:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:37:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:12 INFO - (blobuploader) - INFO - Done attempting. 23:37:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:37:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:13 INFO - (blobuploader) - INFO - Done attempting. 23:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:14 INFO - (blobuploader) - INFO - Done attempting. 23:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:16 INFO - (blobuploader) - INFO - Done attempting. 23:37:16 INFO - (blobuploader) - INFO - Iteration through files over. 23:37:16 INFO - Return code: 0 23:37:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:37:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:37:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b83fdd6b82adb25cb48cceca681cdbc6323d2f621aa2df8016ba75163e1096519d62d56cb434cbda0bbf2edd22fac75613f8f71854ef37ec8f4dcc14f868c8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bab148dea278f150d5d8c51b995262be1b43c6182ce6a769af7630bd6f73e519df3df18f0843b7fae974b38cc031ef9f82b9571aa9ad686a5329a37411f1d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b99e4cf620025a9cae3703c4f5f9051e7f3b570547216467d27cd20767920d3ae183782a1f83cb958fb455c25c4af37f36d4896ed6abb227cbb431993b13e9fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7dbf42aa1b417ba5cbc5233a7b850fd7aa3a0c1ce1a20232df5fa3dde852c469675f411f584ed71998c7e06647ed7598b36f201fed3d841c904b7c46cbd7ed7f"} 23:37:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:37:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:37:16 INFO - Contents: 23:37:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b83fdd6b82adb25cb48cceca681cdbc6323d2f621aa2df8016ba75163e1096519d62d56cb434cbda0bbf2edd22fac75613f8f71854ef37ec8f4dcc14f868c8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bab148dea278f150d5d8c51b995262be1b43c6182ce6a769af7630bd6f73e519df3df18f0843b7fae974b38cc031ef9f82b9571aa9ad686a5329a37411f1d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b99e4cf620025a9cae3703c4f5f9051e7f3b570547216467d27cd20767920d3ae183782a1f83cb958fb455c25c4af37f36d4896ed6abb227cbb431993b13e9fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7dbf42aa1b417ba5cbc5233a7b850fd7aa3a0c1ce1a20232df5fa3dde852c469675f411f584ed71998c7e06647ed7598b36f201fed3d841c904b7c46cbd7ed7f"} 23:37:16 INFO - Running post-run listener: copy_logs_to_upload_dir 23:37:16 INFO - Copying logs to upload dir... 23:37:16 INFO - mkdir: /builds/slave/test/build/upload/logs 23:37:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.558656 ========= master_lag: -0.25 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-28 23:37:15.984669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 23:37:15.988086) ========= 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.4Qci3VFi2N/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.693ZviULlG/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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/Pine/sha512/b83fdd6b82adb25cb48cceca681cdbc6323d2f621aa2df8016ba75163e1096519d62d56cb434cbda0bbf2edd22fac75613f8f71854ef37ec8f4dcc14f868c8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bab148dea278f150d5d8c51b995262be1b43c6182ce6a769af7630bd6f73e519df3df18f0843b7fae974b38cc031ef9f82b9571aa9ad686a5329a37411f1d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b99e4cf620025a9cae3703c4f5f9051e7f3b570547216467d27cd20767920d3ae183782a1f83cb958fb455c25c4af37f36d4896ed6abb227cbb431993b13e9fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7dbf42aa1b417ba5cbc5233a7b850fd7aa3a0c1ce1a20232df5fa3dde852c469675f411f584ed71998c7e06647ed7598b36f201fed3d841c904b7c46cbd7ed7f"} build_url:https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011128 build_url: 'https://queue.taskcluster.net/v1/task/Hv_wjNDAQGqM2kUjQ9OqsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b83fdd6b82adb25cb48cceca681cdbc6323d2f621aa2df8016ba75163e1096519d62d56cb434cbda0bbf2edd22fac75613f8f71854ef37ec8f4dcc14f868c8ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3bab148dea278f150d5d8c51b995262be1b43c6182ce6a769af7630bd6f73e519df3df18f0843b7fae974b38cc031ef9f82b9571aa9ad686a5329a37411f1d16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b99e4cf620025a9cae3703c4f5f9051e7f3b570547216467d27cd20767920d3ae183782a1f83cb958fb455c25c4af37f36d4896ed6abb227cbb431993b13e9fd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7dbf42aa1b417ba5cbc5233a7b850fd7aa3a0c1ce1a20232df5fa3dde852c469675f411f584ed71998c7e06647ed7598b36f201fed3d841c904b7c46cbd7ed7f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 23:37:16.023330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 23:37:16.023630) ========= 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.4Qci3VFi2N/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.693ZviULlG/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.005182 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 23:37:16.071189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 23:37:16.071470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 23:37:16.071764) ========= ========= Total master_lag: 0.16 =========