builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0179 starttime: 1484004736.19 results: success (0) buildid: 20170109122608 builduid: 6925b58af8494641bdec7a7be8d2cbc3 revision: 1c8a9f489bb4bf063b1502222a4cda953d80d7f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.194851) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.195338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.195794) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.221843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.222240) ========= 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.6UbHzVuCma/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.qkVMq3kmSW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-09 15:32:16-- 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% 211M=0s 2017-01-09 15:32:16 (211 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139898 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.392049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.392514) ========= 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.6UbHzVuCma/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.qkVMq3kmSW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.018984 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:16.444214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 15:32:16.444837) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c8a9f489bb4bf063b1502222a4cda953d80d7f6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c8a9f489bb4bf063b1502222a4cda953d80d7f6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6UbHzVuCma/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.qkVMq3kmSW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-09 15:32:16,520 truncating revision to first 12 chars 2017-01-09 15:32:16,520 Setting DEBUG logging. 2017-01-09 15:32:16,520 attempt 1/10 2017-01-09 15:32:16,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1c8a9f489bb4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 15:32:17,343 unpacking tar archive at: mozilla-aurora-1c8a9f489bb4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.072448 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 15:32:17.545464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:17.545852) ========= script_repo_revision: 1c8a9f489bb4bf063b1502222a4cda953d80d7f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:17.546329) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:17.546635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 15:32:17.570170) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-09 15:32:17.571100) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6UbHzVuCma/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.qkVMq3kmSW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:17 INFO - MultiFileLogger online at 20170109 15:32:17 in /builds/slave/test 15:32:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:32:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:32:17 INFO - {'append_to_log': False, 15:32:17 INFO - 'base_work_dir': '/builds/slave/test', 15:32:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:32:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:32:17 INFO - 'branch': 'Mozilla-Aurora', 15:32:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:32:17 INFO - 'config_files': ('talos/mac_config.py',), 15:32:17 INFO - 'default_actions': ('clobber', 15:32:17 INFO - 'read-buildbot-config', 15:32:17 INFO - 'download-and-extract', 15:32:17 INFO - 'populate-webroot', 15:32:17 INFO - 'create-virtualenv', 15:32:17 INFO - 'install', 15:32:17 INFO - 'run-tests'), 15:32:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:32:17 INFO - 'download_minidump_stackwalk': True, 15:32:17 INFO - 'download_symbols': 'ondemand', 15:32:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:32:17 INFO - 'tooltool.py': '/tools/tooltool.py', 15:32:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:32:17 INFO - '/tools/misc-python/virtualenv.py')}, 15:32:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:32:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:32:17 INFO - 'installer_path': 'installer.exe', 15:32:17 INFO - 'log_level': 'info', 15:32:17 INFO - 'log_name': 'talos', 15:32:17 INFO - 'log_to_console': True, 15:32:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:32:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:32:17 INFO - 'opt_config_files': (), 15:32:17 INFO - 'pip_index': False, 15:32:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:32:17 INFO - 'cmd': ('bash', 15:32:17 INFO - '-c', 15:32:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:32:17 INFO - 'enabled': True, 15:32:17 INFO - 'halt_on_failure': False, 15:32:17 INFO - 'name': 'check_screen_resolution'},), 15:32:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:32:17 INFO - 'cmd': ('bash', 15:32:17 INFO - '-c', 15:32:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:32:17 INFO - 'enabled': True, 15:32:17 INFO - 'halt_on_failure': False, 15:32:17 INFO - 'name': 'check_screen_resolution'},), 15:32:17 INFO - 'run_cmd_checks_enabled': True, 15:32:17 INFO - 'sps_profile': False, 15:32:17 INFO - 'sps_profile_interval': 0, 15:32:17 INFO - 'suite': 'dromaeojs', 15:32:17 INFO - 'system_bits': '32', 15:32:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:32:17 INFO - 'title': 't-yosemite-r7-0179', 15:32:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:32:17 INFO - 'use_talos_json': True, 15:32:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:32:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:32:17 INFO - 'work_dir': 'build'} 15:32:17 INFO - [mozharness: 2017-01-09 23:32:17.773388Z] Running clobber step. 15:32:17 INFO - Running pre-action listener: _resource_record_pre_action 15:32:17 INFO - Running main action method: clobber 15:32:17 INFO - rmtree: /builds/slave/test/build 15:32:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:32:19 INFO - Running post-action listener: _resource_record_post_action 15:32:19 INFO - [mozharness: 2017-01-09 23:32:19.383945Z] Finished clobber step (success) 15:32:19 INFO - [mozharness: 2017-01-09 23:32:19.384129Z] Running read-buildbot-config step. 15:32:19 INFO - Running pre-action listener: _resource_record_pre_action 15:32:19 INFO - Running main action method: read_buildbot_config 15:32:19 INFO - Using buildbot properties: 15:32:19 INFO - { 15:32:19 INFO - "project": "", 15:32:19 INFO - "product": "firefox", 15:32:19 INFO - "script_repo_revision": "production", 15:32:19 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:32:19 INFO - "repository": "", 15:32:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:32:19 INFO - "buildid": "20170109122608", 15:32:19 INFO - "pgo_build": "False", 15:32:19 INFO - "basedir": "/builds/slave/test", 15:32:19 INFO - "buildnumber": 0, 15:32:19 INFO - "slavename": "t-yosemite-r7-0179", 15:32:19 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:32:19 INFO - "platform": "yosemite_r7", 15:32:19 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:32:19 INFO - "branch": "mozilla-aurora", 15:32:19 INFO - "repo_path": "releases/mozilla-aurora", 15:32:19 INFO - "revision": "1c8a9f489bb4bf063b1502222a4cda953d80d7f6", 15:32:19 INFO - "stage_platform": "macosx64", 15:32:19 INFO - "builduid": "6925b58af8494641bdec7a7be8d2cbc3", 15:32:19 INFO - "slavebuilddir": "test" 15:32:19 INFO - } 15:32:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 15:32:19 INFO - Running post-action listener: _resource_record_post_action 15:32:19 INFO - [mozharness: 2017-01-09 23:32:19.387526Z] Finished read-buildbot-config step (success) 15:32:19 INFO - [mozharness: 2017-01-09 23:32:19.387664Z] Running download-and-extract step. 15:32:19 INFO - Running pre-action listener: _resource_record_pre_action 15:32:19 INFO - Running main action method: download_and_extract 15:32:19 INFO - mkdir: /builds/slave/test/build/tests 15:32:19 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')]} 15:32:19 INFO - trying https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 15:32:19 INFO - Downloading https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:32:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 15:32:20 INFO - Downloaded 1199 bytes. 15:32:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 15:32:20 INFO - Using the following test package requirements: 15:32:20 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:32:20 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 15:32:20 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 15:32:20 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'jsshell-mac.zip'], 15:32:20 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 15:32:20 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 15:32:20 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 15:32:20 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 15:32:20 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 15:32:20 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 15:32:20 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 15:32:20 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:32:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:32:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:32:20 INFO - Fetch https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:32:21 INFO - Http code: 200 15:32:21 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 15:32:21 INFO - x-amz-cf-id: u6txVJa0Aq7w2SbR_8y70IrV_CGRxr8lk8c2wdv1xB3zLiGiVyGzGA== 15:32:21 INFO - x-amz-version-id: _qBfprTfWpERFQRMsTcLI4wNOayOpcfj 15:32:21 INFO - Expected file size: 16270970 15:32:21 INFO - Obtained file size: 16270970 15:32:28 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:32:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 15:32:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:32:28 INFO - Fetch https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 15:32:28 INFO - Http code: 200 15:32:28 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 15:32:28 INFO - x-amz-cf-id: J7Q0VtV8KCvzFmr-WpeZl0DMS3T0Bj_EPJQBNI8RoLnmTfq5f9HfqA== 15:32:28 INFO - x-amz-version-id: _qBfprTfWpERFQRMsTcLI4wNOayOpcfj 15:32:29 INFO - Expected file size: 16270970 15:32:29 INFO - Obtained file size: 16270970 15:32:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 15:32:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:32:35 INFO - Fetch https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 15:32:36 INFO - Http code: 200 15:32:36 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:32:36 INFO - x-amz-cf-id: DveFH2CnasGWURGceexu25Cq5AwRjplBoiWHPAOGsDx7iiDXrNfVBw== 15:32:36 INFO - x-amz-version-id: JiweLtwMXup6OP7gCnIiwRul_iaCHJFw 15:32:36 INFO - Expected file size: 14409038 15:32:36 INFO - Obtained file size: 14409038 15:32:37 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')]} 15:32:37 INFO - trying https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:32:37 INFO - Downloading https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 15:32:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 15:32:39 INFO - Downloaded 109880177 bytes. 15:32:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:32:39 INFO - mkdir: /builds/slave/test/properties 15:32:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:32:39 INFO - Writing to file /builds/slave/test/properties/build_url 15:32:39 INFO - Contents: 15:32:39 INFO - build_url:https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 15:32:40 INFO - Running post-action listener: _resource_record_post_action 15:32:40 INFO - Running post-action listener: set_extra_try_arguments 15:32:40 INFO - [mozharness: 2017-01-09 23:32:40.543770Z] Finished download-and-extract step (success) 15:32:40 INFO - [mozharness: 2017-01-09 23:32:40.543894Z] Running populate-webroot step. 15:32:40 INFO - Running pre-action listener: _resource_record_pre_action 15:32:40 INFO - Running main action method: populate_webroot 15:32:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:32:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:32:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:32:40 INFO - '--tppagecycles', 15:32:40 INFO - '7'], 15:32:40 INFO - 'tests': ['tsvgm']}}, 15:32:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:32:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:32:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:32:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:32:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['damp', 'tps']}, 15:32:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'tests': ['damp', 'tps']}, 15:32:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['dromaeo_dom']}, 15:32:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:32:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['basic_compositor_video']}, 15:32:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:32:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['a11yr', 15:32:40 INFO - 'ts_paint', 15:32:40 INFO - 'tpaint', 15:32:40 INFO - 'sessionrestore', 15:32:40 INFO - 'sessionrestore_no_auto_restore', 15:32:40 INFO - 'tabpaint']}, 15:32:40 INFO - 'other-e10s': {'tests': ['a11yr', 15:32:40 INFO - 'ts_paint', 15:32:40 INFO - 'tpaint', 15:32:40 INFO - 'sessionrestore', 15:32:40 INFO - 'sessionrestore_no_auto_restore', 15:32:40 INFO - 'tabpaint']}, 15:32:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:32:40 INFO - 'ts_paint', 15:32:40 INFO - 'tpaint', 15:32:40 INFO - 'sessionrestore', 15:32:40 INFO - 'sessionrestore_no_auto_restore', 15:32:40 INFO - 'tabpaint']}, 15:32:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:32:40 INFO - 'ts_paint', 15:32:40 INFO - 'tpaint', 15:32:40 INFO - 'sessionrestore', 15:32:40 INFO - 'sessionrestore_no_auto_restore', 15:32:40 INFO - 'tabpaint']}, 15:32:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['a11yr', 15:32:40 INFO - 'ts_paint', 15:32:40 INFO - 'tpaint', 15:32:40 INFO - 'sessionrestore', 15:32:40 INFO - 'sessionrestore_no_auto_restore', 15:32:40 INFO - 'tabpaint']}, 15:32:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['a11yr', 15:32:40 INFO - 'ts_paint', 15:32:40 INFO - 'tpaint', 15:32:40 INFO - 'sessionrestore', 15:32:40 INFO - 'sessionrestore_no_auto_restore', 15:32:40 INFO - 'tabpaint']}, 15:32:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['tsvgx', 15:32:40 INFO - 'tsvgr_opacity', 15:32:40 INFO - 'tart', 15:32:40 INFO - 'tscrollx', 15:32:40 INFO - 'cart']}, 15:32:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:32:40 INFO - 'tsvgr_opacity', 15:32:40 INFO - 'tart', 15:32:40 INFO - 'tscrollx', 15:32:40 INFO - 'cart']}, 15:32:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'talos_options': ['--disable-e10s'], 15:32:40 INFO - 'tests': ['tp5o']}, 15:32:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'tests': ['tp5o']}, 15:32:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'talos_options': ['--disable-e10s', 15:32:40 INFO - '--xperf_path', 15:32:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:40 INFO - 'tests': ['tp5n']}, 15:32:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:40 INFO - 'talos_options': ['--xperf_path', 15:32:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:40 INFO - 'tests': ['tp5n']}}, 15:32:40 INFO - 'talos.zip': {'path': '', 15:32:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:32:40 INFO - Running post-action listener: _resource_record_post_action 15:32:40 INFO - [mozharness: 2017-01-09 23:32:40.562462Z] Finished populate-webroot step (success) 15:32:40 INFO - [mozharness: 2017-01-09 23:32:40.562593Z] Running create-virtualenv step. 15:32:40 INFO - Running pre-action listener: _resource_record_pre_action 15:32:40 INFO - Running main action method: create_virtualenv 15:32:40 INFO - Creating virtualenv /builds/slave/test/build/venv 15:32:40 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 15:32:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:32:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:32:40 INFO - Using real prefix '/tools/python27' 15:32:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:32:41 INFO - Installing distribute.............................................................................................................................................................................................done. 15:32:44 INFO - Installing pip.................done. 15:32:44 INFO - Return code: 0 15:32:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:32:44 INFO - Reading from file tmpfile_stdout 15:32:44 INFO - Output received: 15:32:44 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) 15:32:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:32:44 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')]} 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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')]} 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:44 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 15:32:44 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 15:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:44 INFO - 'HOME': '/Users/cltbld', 15:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:44 INFO - 'LOGNAME': 'cltbld', 15:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:44 INFO - 'MOZ_NO_REMOTE': '1', 15:32:44 INFO - 'NO_EM_RESTART': '1', 15:32:44 INFO - 'PAGER': '/bin/cat', 15:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:44 INFO - 'PWD': '/builds/slave/test', 15:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:44 INFO - 'SHELL': '/bin/bash', 15:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:32:44 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:44 INFO - 'USER': 'cltbld', 15:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:44 INFO - 'XPC_FLAGS': '0x0', 15:32:44 INFO - 'XPC_SERVICE_NAME': '0', 15:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:44 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 15:32:44 INFO - Cleaning up... 15:32:44 INFO - Return code: 0 15:32:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:32:44 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')]} 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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')]} 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:44 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 15:32:44 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 15:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:44 INFO - 'HOME': '/Users/cltbld', 15:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:44 INFO - 'LOGNAME': 'cltbld', 15:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:44 INFO - 'MOZ_NO_REMOTE': '1', 15:32:44 INFO - 'NO_EM_RESTART': '1', 15:32:44 INFO - 'PAGER': '/bin/cat', 15:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:44 INFO - 'PWD': '/builds/slave/test', 15:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:44 INFO - 'SHELL': '/bin/bash', 15:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:32:44 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:44 INFO - 'USER': 'cltbld', 15:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:44 INFO - 'XPC_FLAGS': '0x0', 15:32:44 INFO - 'XPC_SERVICE_NAME': '0', 15:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:45 INFO - Downloading/unpacking psutil>=3.1.1 15:32:45 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 15:32:45 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 15:32:45 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 15:32:45 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 15:32:45 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 15:32:45 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 15:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:32:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:48 INFO - Installing collected packages: psutil 15:32:48 INFO - Running setup.py install for psutil 15:32:48 INFO - building 'psutil._psutil_osx' extension 15:32:48 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 15:32:48 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 15:32:48 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 15:32:49 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 15:32:49 INFO - building 'psutil._psutil_posix' extension 15:32:49 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 15:32:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:32:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:32:49 INFO - ^ 15:32:49 INFO - 1 warning generated. 15:32:49 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 15:32:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:49 INFO - Successfully installed psutil 15:32:49 INFO - Cleaning up... 15:32:49 INFO - Return code: 0 15:32:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:49 INFO - 'HOME': '/Users/cltbld', 15:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:49 INFO - 'LOGNAME': 'cltbld', 15:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:49 INFO - 'MOZ_NO_REMOTE': '1', 15:32:49 INFO - 'NO_EM_RESTART': '1', 15:32:49 INFO - 'PAGER': '/bin/cat', 15:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:49 INFO - 'PWD': '/builds/slave/test', 15:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:49 INFO - 'SHELL': '/bin/bash', 15:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:32:49 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:49 INFO - 'USER': 'cltbld', 15:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:49 INFO - 'XPC_FLAGS': '0x0', 15:32:49 INFO - 'XPC_SERVICE_NAME': '0', 15:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:32:49 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 15:32:49 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 15:32:49 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 15:32:49 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 15:32:49 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 15:32:49 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 15:32:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:32:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:32:52 INFO - Installing collected packages: mozsystemmonitor 15:32:52 INFO - Running setup.py install for mozsystemmonitor 15:32:52 INFO - Successfully installed mozsystemmonitor 15:32:52 INFO - Cleaning up... 15:32:52 INFO - Return code: 0 15:32:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:32:52 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')]} 15:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:52 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')]} 15:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:52 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:52 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 15:32:52 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 15:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:52 INFO - 'HOME': '/Users/cltbld', 15:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:52 INFO - 'LOGNAME': 'cltbld', 15:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:52 INFO - 'MOZ_NO_REMOTE': '1', 15:32:52 INFO - 'NO_EM_RESTART': '1', 15:32:52 INFO - 'PAGER': '/bin/cat', 15:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:52 INFO - 'PWD': '/builds/slave/test', 15:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:52 INFO - 'SHELL': '/bin/bash', 15:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:32:52 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:52 INFO - 'USER': 'cltbld', 15:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:52 INFO - 'XPC_FLAGS': '0x0', 15:32:52 INFO - 'XPC_SERVICE_NAME': '0', 15:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:52 INFO - Downloading/unpacking jsonschema==2.5.1 15:32:52 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 15:32:52 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 15:32:52 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 15:32:52 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 15:32:52 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 15:32:52 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 15:32:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:32:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:32:55 INFO - warning: no files found matching '*.txt' 15:32:55 INFO - no previously-included directories found matching 'build' 15:32:56 INFO - no previously-included directories found matching 'dist' 15:32:56 INFO - no previously-included directories found matching '.git*' 15:32:56 INFO - Installing collected packages: jsonschema, functools32 15:32:56 INFO - Running setup.py install for functools32 15:32:56 INFO - warning: no files found matching '*.txt' 15:32:56 INFO - no previously-included directories found matching 'build' 15:32:56 INFO - no previously-included directories found matching 'dist' 15:32:56 INFO - no previously-included directories found matching '.git*' 15:32:56 INFO - Successfully installed jsonschema functools32 15:32:56 INFO - Cleaning up... 15:32:56 INFO - Return code: 0 15:32:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:56 INFO - 'HOME': '/Users/cltbld', 15:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:56 INFO - 'LOGNAME': 'cltbld', 15:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:56 INFO - 'MOZ_NO_REMOTE': '1', 15:32:56 INFO - 'NO_EM_RESTART': '1', 15:32:56 INFO - 'PAGER': '/bin/cat', 15:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:56 INFO - 'PWD': '/builds/slave/test', 15:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:56 INFO - 'SHELL': '/bin/bash', 15:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:32:56 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:56 INFO - 'USER': 'cltbld', 15:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:56 INFO - 'XPC_FLAGS': '0x0', 15:32:56 INFO - 'XPC_SERVICE_NAME': '0', 15:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:32:56 INFO - Cleaning up... 15:32:56 INFO - Return code: 0 15:32:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:32:56 INFO - 'HOME': '/Users/cltbld', 15:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:32:56 INFO - 'LOGNAME': 'cltbld', 15:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:56 INFO - 'MOZ_NO_REMOTE': '1', 15:32:56 INFO - 'NO_EM_RESTART': '1', 15:32:56 INFO - 'PAGER': '/bin/cat', 15:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:56 INFO - 'PWD': '/builds/slave/test', 15:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:32:56 INFO - 'SHELL': '/bin/bash', 15:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:32:56 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:32:56 INFO - 'USER': 'cltbld', 15:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:56 INFO - 'XPC_FLAGS': '0x0', 15:32:56 INFO - 'XPC_SERVICE_NAME': '0', 15:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:59 INFO - Downloading blobuploader-1.2.4.tar.gz 15:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:32:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:33:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:33:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:33:00 INFO - Downloading docopt-0.6.1.tar.gz 15:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:33:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:33:00 INFO - Running setup.py install for blobuploader 15:33:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:33:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:33:00 INFO - Running setup.py install for requests 15:33:01 INFO - Running setup.py install for docopt 15:33:01 INFO - Successfully installed blobuploader requests docopt 15:33:01 INFO - Cleaning up... 15:33:01 INFO - Return code: 0 15:33:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:01 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')]} 15:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:01 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')]} 15:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:01 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:33:01 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 15:33:01 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 15:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:01 INFO - 'HOME': '/Users/cltbld', 15:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:01 INFO - 'LOGNAME': 'cltbld', 15:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:01 INFO - 'MOZ_NO_REMOTE': '1', 15:33:01 INFO - 'NO_EM_RESTART': '1', 15:33:01 INFO - 'PAGER': '/bin/cat', 15:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:01 INFO - 'PWD': '/builds/slave/test', 15:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:01 INFO - 'SHELL': '/bin/bash', 15:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:33:01 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:01 INFO - 'USER': 'cltbld', 15:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:01 INFO - 'XPC_FLAGS': '0x0', 15:33:01 INFO - 'XPC_SERVICE_NAME': '0', 15:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:33:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-PLWLUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:33:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-QewToW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-kdXWH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-R6CqzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-5JVL4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ode18D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Qykoxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Z35GkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-nStB9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-PCwBji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:33:02 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-fwltNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:33:03 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-4U6zyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:33:03 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-vEx1rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:33:03 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Gp_uzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:03 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-VH7s2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:33:03 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ZsJI4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:03 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-I2DxkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:33:03 INFO - Running setup.py install for manifestparser 15:33:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:33:03 INFO - Running setup.py install for mozcrash 15:33:03 INFO - Running setup.py install for mozdebug 15:33:04 INFO - Running setup.py install for mozdevice 15:33:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Running setup.py install for mozfile 15:33:04 INFO - Running setup.py install for mozhttpd 15:33:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Running setup.py install for mozinfo 15:33:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Running setup.py install for mozInstall 15:33:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:33:04 INFO - Running setup.py install for mozleak 15:33:04 INFO - Running setup.py install for mozlog 15:33:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Running setup.py install for moznetwork 15:33:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Running setup.py install for mozprocess 15:33:05 INFO - Running setup.py install for mozprofile 15:33:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Running setup.py install for mozrunner 15:33:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:33:05 INFO - Running setup.py install for mozscreenshot 15:33:05 INFO - Running setup.py install for moztest 15:33:06 INFO - Running setup.py install for mozversion 15:33:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:33:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:33:06 INFO - Cleaning up... 15:33:06 INFO - Return code: 0 15:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:06 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')]} 15:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:06 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')]} 15:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:06 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:33:06 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 15:33:06 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 15:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:06 INFO - 'HOME': '/Users/cltbld', 15:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:06 INFO - 'LOGNAME': 'cltbld', 15:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:06 INFO - 'MOZ_NO_REMOTE': '1', 15:33:06 INFO - 'NO_EM_RESTART': '1', 15:33:06 INFO - 'PAGER': '/bin/cat', 15:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:06 INFO - 'PWD': '/builds/slave/test', 15:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:06 INFO - 'SHELL': '/bin/bash', 15:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:33:06 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:06 INFO - 'USER': 'cltbld', 15:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:06 INFO - 'XPC_FLAGS': '0x0', 15:33:06 INFO - 'XPC_SERVICE_NAME': '0', 15:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-AQLk5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:33:06 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)) 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-441Um_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:33:06 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)) 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-K47uJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:33:06 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)) 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-015JiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:33:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:33:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:33:06 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-LWLxSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ej0Yp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-2MH0uG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-MEQ2l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-JWr5Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-IwU6td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-7awRp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-T_tYWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:33:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-RCjP6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:33:07 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)) 15:33:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:33:07 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-mGkKtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:33:08 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)) 15:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:08 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-YiC5Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:33:08 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)) 15:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:33:08 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-83xMmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:33:08 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)) 15:33:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:33:08 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-HhHejG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:33:08 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)) 15:33:08 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)) 15:33:08 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)) 15:33:08 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)) 15:33:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:33:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:33:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:33:08 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 15:33:08 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 15:33:08 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 15:33:08 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 15:33:08 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 15:33:08 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 15:33:10 INFO - Downloading blessings-1.6.tar.gz 15:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:33:11 INFO - Installing collected packages: blessings 15:33:11 INFO - Running setup.py install for blessings 15:33:11 INFO - Successfully installed blessings 15:33:11 INFO - Cleaning up... 15:33:11 INFO - Return code: 0 15:33:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:33:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:33:11 INFO - Reading from file tmpfile_stdout 15:33:11 INFO - Current package versions: 15:33:11 INFO - blessings == 1.6 15:33:11 INFO - blobuploader == 1.2.4 15:33:11 INFO - docopt == 0.6.1 15:33:11 INFO - functools32 == 3.2.3-2 15:33:11 INFO - jsonschema == 2.5.1 15:33:11 INFO - manifestparser == 1.1 15:33:11 INFO - mozInstall == 1.12 15:33:11 INFO - mozcrash == 1.0 15:33:11 INFO - mozdebug == 0.1 15:33:11 INFO - mozdevice == 0.48 15:33:11 INFO - mozfile == 1.2 15:33:11 INFO - mozhttpd == 0.7 15:33:11 INFO - mozinfo == 0.9 15:33:11 INFO - mozleak == 0.1 15:33:11 INFO - mozlog == 3.4 15:33:11 INFO - moznetwork == 0.27 15:33:11 INFO - mozprocess == 0.23 15:33:11 INFO - mozprofile == 0.28 15:33:11 INFO - mozrunner == 6.13 15:33:11 INFO - mozscreenshot == 0.1 15:33:11 INFO - mozsystemmonitor == 0.3 15:33:11 INFO - moztest == 0.8 15:33:11 INFO - mozversion == 1.4 15:33:11 INFO - psutil == 3.1.1 15:33:11 INFO - requests == 1.2.3 15:33:11 INFO - wsgiref == 0.1.2 15:33:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:33:11 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')]} 15:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:11 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')]} 15:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:12 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:33:12 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 15:33:12 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 15:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:12 INFO - 'HOME': '/Users/cltbld', 15:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:12 INFO - 'LOGNAME': 'cltbld', 15:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:12 INFO - 'MOZ_NO_REMOTE': '1', 15:33:12 INFO - 'NO_EM_RESTART': '1', 15:33:12 INFO - 'PAGER': '/bin/cat', 15:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:12 INFO - 'PWD': '/builds/slave/test', 15:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:12 INFO - 'SHELL': '/bin/bash', 15:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:33:12 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:12 INFO - 'USER': 'cltbld', 15:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:12 INFO - 'XPC_FLAGS': '0x0', 15:33:12 INFO - 'XPC_SERVICE_NAME': '0', 15:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 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)) 15:33:12 INFO - Cleaning up... 15:33:12 INFO - Return code: 0 15:33:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:33:13 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 0x10ee4c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdda2482070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, '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.6UbHzVuCma/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.qkVMq3kmSW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 15:33:13 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 15:33:13 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 15:33:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:33:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:13 INFO - 'HOME': '/Users/cltbld', 15:33:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:13 INFO - 'LOGNAME': 'cltbld', 15:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:13 INFO - 'MOZ_NO_REMOTE': '1', 15:33:13 INFO - 'NO_EM_RESTART': '1', 15:33:13 INFO - 'PAGER': '/bin/cat', 15:33:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:13 INFO - 'PWD': '/builds/slave/test', 15:33:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:13 INFO - 'SHELL': '/bin/bash', 15:33:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:33:13 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:33:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:13 INFO - 'USER': 'cltbld', 15:33:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:13 INFO - 'XPC_FLAGS': '0x0', 15:33:13 INFO - 'XPC_SERVICE_NAME': '0', 15:33:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:33:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:33:13 INFO - Cleaning up... 15:33:13 INFO - Return code: 0 15:33:13 INFO - Running post-action listener: _resource_record_post_action 15:33:13 INFO - Running post-action listener: _start_resource_monitoring 15:33:13 INFO - Starting resource monitoring. 15:33:13 INFO - [mozharness: 2017-01-09 23:33:13.493670Z] Finished create-virtualenv step (success) 15:33:13 INFO - [mozharness: 2017-01-09 23:33:13.494655Z] Running install step. 15:33:13 INFO - Running pre-action listener: _resource_record_pre_action 15:33:13 INFO - Running main action method: install 15:33:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:33:13 INFO - Reading from file tmpfile_stdout 15:33:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:33:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:33:13 INFO - Reading from file tmpfile_stdout 15:33:13 INFO - Output received: 15:33:13 INFO - Usage: mozinstall [options] installer 15:33:13 INFO - Options: 15:33:13 INFO - -h, --help show this help message and exit 15:33:13 INFO - -d DEST, --destination=DEST 15:33:13 INFO - Directory to install application into. [default: 15:33:13 INFO - "/builds/slave/test"] 15:33:13 INFO - --app=APP Application being installed. [default: firefox] 15:33:13 INFO - mkdir: /builds/slave/test/build/application 15:33:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:33:45 INFO - Reading from file tmpfile_stdout 15:33:45 INFO - Output received: 15:33:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:33:45 INFO - Running post-action listener: _resource_record_post_action 15:33:45 INFO - [mozharness: 2017-01-09 23:33:45.137191Z] Finished install step (success) 15:33:45 INFO - [mozharness: 2017-01-09 23:33:45.137366Z] Running run-tests step. 15:33:45 INFO - Running pre-action listener: _resource_record_pre_action 15:33:45 INFO - Running main action method: run_tests 15:33:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:33:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:33:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:33:45 INFO - 2017-01-09 15:33:45.151 screenresolution[1917:18017] starting screenresolution argv=screenresolution get 15:33:45 INFO - 2017-01-09 15:33:45.153 screenresolution[1917:18017] Display 0: 1600x1200x32@60 15:33:45 INFO - 2017-01-09 15:33:45.158 screenresolution[1918:18019] starting screenresolution argv=screenresolution list 15:33:45 INFO - Available Modes on Display 0 15:33:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:33:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:33:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:33:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:33:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:33:45 INFO - Intel Iris: 15:33:45 INFO - Chipset Model: Intel Iris 15:33:45 INFO - Type: GPU 15:33:45 INFO - Bus: Built-In 15:33:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:33:45 INFO - Vendor: Intel (0x8086) 15:33:45 INFO - Device ID: 0x0a2e 15:33:45 INFO - Revision ID: 0x0009 15:33:45 INFO - Displays: 15:33:45 INFO - Display: 15:33:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:33:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:33:45 INFO - Display Serial Number: 1600x1200 60 15:33:45 INFO - Main Display: Yes 15:33:45 INFO - Mirror: Off 15:33:45 INFO - Online: Yes 15:33:45 INFO - Rotation: Supported 15:33:45 INFO - Return code: 0 15:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:33:45 INFO - Python 2.7.3 15:33:45 INFO - Return code: 0 15:33:45 INFO - grabbing minidump binary from tooltool 15:33:45 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')]} 15:33:45 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 0x10ec60d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec58b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:33:45 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 15:33:45 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 15:33:45 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 15:33:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:33:45 INFO - Return code: 0 15:33:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:33:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:33:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:33:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:33:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 15:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --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 15:33:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UbHzVuCma/Render', 15:33:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:33:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:33:45 INFO - 'HOME': '/Users/cltbld', 15:33:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:33:45 INFO - 'LOGNAME': 'cltbld', 15:33:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:33:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:45 INFO - 'MOZ_NO_REMOTE': '1', 15:33:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:33:45 INFO - 'NO_EM_RESTART': '1', 15:33:45 INFO - 'PAGER': '/bin/cat', 15:33:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:33:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:45 INFO - 'PWD': '/builds/slave/test', 15:33:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:33:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:33:45 INFO - 'SHELL': '/bin/bash', 15:33:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qkVMq3kmSW/Listeners', 15:33:45 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 15:33:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:33:45 INFO - 'USER': 'cltbld', 15:33:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:33:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:45 INFO - 'XPC_FLAGS': '0x0', 15:33:45 INFO - 'XPC_SERVICE_NAME': '0', 15:33:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:33:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 15:33:45 INFO - mozversion application_buildid: 20170109122608 15:33:45 INFO - mozversion application_changeset: 1c8a9f489bb4bf063b1502222a4cda953d80d7f6 15:33:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:33:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:33:45 INFO - mozversion application_name: Firefox 15:33:45 INFO - mozversion application_remotingname: firefox-dev 15:33:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:33:45 INFO - mozversion application_vendor: Mozilla 15:33:45 INFO - mozversion application_version: 52.0a2 15:33:45 INFO - mozversion platform_buildid: 20170109122608 15:33:45 INFO - mozversion platform_changeset: 1c8a9f489bb4bf063b1502222a4cda953d80d7f6 15:33:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:33:45 INFO - mozversion platform_version: 52.0a2 15:33:45 INFO - using testdate: 1484004825 15:33:45 INFO - actual date: 1484004825 15:33:45 INFO - starting webserver on 'localhost:54567' 15:33:45 INFO - SUITE-START | Running 2 tests 15:33:45 INFO - TEST-START | dromaeo_css 15:33:45 INFO - operating with platform_type : mac_ 15:33:45 INFO - Initialising browser for dromaeo_css test... 15:33:45 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpszOGnF/profile http://localhost:54567/getInfo.html) 15:33:48 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 15:33:48 INFO - PROCESS | 1928 | colorDepth:24 15:33:48 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 15:33:48 INFO - PROCESS | 1928 | __metrics 15:33:48 INFO - PROCESS | 1928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:33:48 INFO - PROCESS | 1928 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:49 INFO - TEST-INFO | 1928: exit 0 15:33:49 INFO - Browser initialized. 15:33:49 INFO - Running cycle 1/1 for dromaeo_css test... 15:33:49 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpszOGnF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:01 INFO - PROCESS | 1930 | RSS: Main: 223358976 15:34:01 INFO - PROCESS | 1930 | 15:37:06 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:54567/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54567/tests/dromaeo/cssquery-ext.html) 15:37:08 INFO - PROCESS | 1930 | RSS: Main: 353107968 15:37:08 INFO - PROCESS | 1930 | 15:40:10 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:54567/tests/dromaeo/cssquery-ext.html (next: http://localhost:54567/tests/dromaeo/cssquery-jquery.html) 15:40:11 INFO - PROCESS | 1930 | RSS: Main: 347312128 15:40:11 INFO - PROCESS | 1930 | 15:43:14 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:54567/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54567/tests/dromaeo/cssquery-mootools.html) 15:43:14 INFO - PROCESS | 1930 | RSS: Main: 345120768 15:43:14 INFO - PROCESS | 1930 | 15:46:16 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:54567/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54567/tests/dromaeo/cssquery-prototype.html) 15:46:17 INFO - PROCESS | 1930 | RSS: Main: 320225280 15:46:17 INFO - PROCESS | 1930 | 15:49:20 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:54567/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54567/tests/dromaeo/cssquery-yui.html) 15:49:21 INFO - PROCESS | 1930 | RSS: Main: 305692672 15:49:21 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:54567/tests/dromaeo/cssquery-yui.html (next: http://localhost:54567/tests/dromaeo/cssquery-dojo.html) 15:52:24 INFO - PROCESS | 1930 | RSS: Main: 484438016 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | __start_tp_report 15:52:24 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 15:52:24 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 15:52:24 INFO - PROCESS | 1930 | |i|pagename|runs| 15:52:24 INFO - PROCESS | 1930 | |0;dojo.html;2915.402342519783;3291.9670803291974;3320.850561724722;3325.2185736351967;3381.7294616430686;4665.393498845149;5576.944230557693;5667.404922483137;5670.631408958415;5704.229928959594;5576.33084029917;5620.494155526002;5629.887402251952;5663.603547751659;5664.348599911012;5090.669106508074;5108.029474399871;5115.718635475047;5177.456367081459;5253.369595648527;5097.821576244833;5106.463821298766;5120.334356533645;5158.638895277332;5241.318628578299;5095.898082038357;5182.455842136578;5183.688978661274;5197.350331208599;5260.579153667716;5189.688618682872;5195.246689230078;5212.296339994128;5232.372115346145;5260.947390526084;4996.400431948153;5140.974295128538;5155.587552995751;5191.922121168185;5200.881810410742;5573.749181286811;5595.972020139873;5598.328200615912;5619.943800561983;5645.717714114277;6104.572679912408;6132.080187971796;6161.260648722137;6213.658248796314;6226.875462490725;6284.151639528696;6304.117423560705;6307.96846015767;6314.273858506284;6321.74713011479;5622.690752008597;5641.266635337462;5660.801871934463;5679.6876171810945;5782.624129431573;6362.491000719931;6388.201474815648;6406.103145559626;6408.03879418091;6416.807495775134;6045.546584006217;6054.455099041107;6057.666828324484;6060.2424696912885;6072.149899014142;5903.498202652737;5932.080527518242;5958.761649534057;6205.968970155211;6224.719887605069;5931.614445061057;5932.881342373128;5946.43508866657;5948.851278718067;5950.226470558886;5909.261342332208;5967.582269241111;6104.236970378703;6143.894099062211;6211.627302361872;4884;4900.5099490051425;4911.115999120121;4929.433115191727;4930.137225985438;3668.523091998023;4361.280388735887;4370.475542934804;4372.6939114261695;4403.405540252025;5571.916421253684;5627.943720562742;5627.971860140673;5652.0674088774895;5669;5176.249443830623;6211.689415529206;6272.435480806749;6275.152854364694;6292.968535157386;5667.036603777367;5708.800191993343;5710.7430165642645;5713.085906255063;5723;5237.423883372826;5313.0436521425745;5373.328333958255;5382.327209098863;5397;6288.025356069817;6347.777827776005;6353.269374021862;6372.74509019634;6418.839529011812;6087.421694938974;6225.875482490234;6238.625682459067;6262.34245404226;6266.248050234001;6352.645361982474;6388.8083357499345;6416.903746443713;6420.101185833893;6440.420362167428;5939.851503712442;5973.522118230444;6098.176746139215;6098.481629061579;6133.080037994337;6035.849103772265;6159.5688301818445;6222.80135477234;6227.034809604518;6251.9062214067735;5200.973995130152;5625.915611265752;6020.668863212565;6027.78902738402;6044.335123136363;5747.597668163188;5905;5967.194428752063;5973.253343332083;5978;5896;5913.645181289136;5916.55625828056;5921.970390148022;5925.792597259076;6035.637861728134;6046.062860256668;6051.334353221054;6060.393960603904;6077.848053798691;5696.886062278814;5697.316322041382;5808.883822323445;5863.824085277449;5875.089361149029 15:52:24 INFO - PROCESS | 1930 | |1;ext.html;450.6057199950044;521.7052365413541;523.7695414017832;538.3378444513248;553.765103818485;29850.552241716363;38909.83270501886;40263.59736402635;40325;42096.78951605246;40480.79759601205;40578.59421405785;40975.59024409755;40995.59004409955;41003.58996410035;9312.720618381425;9491;9505.14453699167;9506.857397139049;9510.952445237765;8009.519428834259;9514.904850951505;9517.000714924938;9528.809423811519;9543.141117299441;9459.479728614924;9525.142737153656;9532.523373831315;9533.427994320364;9540.713778586654;9464.053594640482;9483.383580067308;9499.477528735917;9504.572294246705;9506.19197368228;9372.812543749154;9446.811063778687;9447.480388578626;9450.243980481546;9453.858192127123;38964.61035389638;39248;39292.80353598214;39292.80353598242;39296.41055384143;3262.1029216965285;3297.1262615406936;3357.7481688873186;3533.5406397168204;3818.9045273868096;28202;28400.715992840014;29824.807007719664;30557.694423055706;30721.231969200726;3114.283714745621;3176.7140957313954;3178.078357276387;3243.448613735647;3287;486212;489103;489275.99999999645;489391;503398;33375;33942.49086263708;34382.14044648904;58388.4161158383;58923.11615325774;35556;35650.465243021375;35667.82166089153;35680.107997299754;35805;14117;14242.931780116533;14247.715045699028;14258.928705356408;14287.357068931922;11802.350870702023;11858.407079645984;11865.288082715064;11865.822012669816;11874.643760687193;7079.725649383057;7782.260685234894;7804.726834560764;7846.8430631387055;7849;2733.8223015503927;3266.3140740444296;3291.078498020558;3303.2072302647193;3335.216224187314;893.4728510179026;920.3741455810114;972.6060945317016;1025.6820385680462;1025.7640742629237;28227;29094.85452572766;29163.27091822679;29935.80256789704;30009.549856752164;856.2465030773004;911.2618778789202;932.1936524905908;932.342698397628;1021.3361315145215;15120.395184192728;15189.088654680754;15281.694366112615;15548.067115973077;15600.453984110502;671613;678939;679407;680303;681665.5916720384;56746.43253567494;57221;57246;57396.139057914166;57688;1212.3938030984507;1248.525560287085;1328.6147017365242;1350.2371160294401;1371.8490965993733;24113;28397.574036389058;29703.851480742458;30178.849105754332;30996.0701178965;48356.75821620869;48915.0216995651;48915.266271005254;48959;49145.50854491409;548423;551611;551965;552102;598069.009654949;39800.2039959205;40032.599674002886;40316.79841600773;40343.1931361377;40626.593734062284;35820.10449738777;44552.77723611361;44653.77673111744;44822.551774481835;45578.31632525581;36400.81799590985;36419.817900910326;36755.63244367522;37724;38096.61903381041;36310.455343170404;36673.89978300655;36787.44818827773;37268.6273137276;37800;35570.822145889106;36011.45982810312;36361.636383636884;36431;36734.265314694094;31211.843940780152;33466.49800253047;33641.495377569845;33736.831315843265;33848.66151338455;24319.270421886664;24764.133255335975;25015.374615634755;25305.797462055238;25316.493670126863 15:52:24 INFO - PROCESS | 1930 | |2;jquery.html;319.742607201203;352.8906039127869;378.479590562976;381.7709374375375;404.6156151655926;14862.925685371572;15039.624009399771;15897.52307430776;20099;20501.897490512525;19246.03769811511;19674.606507869834;19782.70325945112;19891.40325790225;19947.900260498714;5653.180288858113;5962.284525856881;5964.194833697438;5965.045592705146;5972.611780234292;5305.885763989567;5523.1163013917785;5802.564807639423;5861.238039054917;5872.530197584183;5782.681952492611;5809.82570522885;5817.214676018747;5818.534517238632;5827.096799996008;5561.082421400464;5850.824475265749;5920.111983202511;5953.2856057272975;5966.642001479881;5532.7786888524415;5630.859228519279;5632.943670563324;5707.62900411476;5753.597248192628;12869.549565765154;13091.41088651013;13092.476300948047;13115.475380984844;17738;2152.7416709994745;2184.803367696915;2265.127925748582;2282.1898226129815;2320.5474932388192;8015.839683206362;10250.38497690141;10294.948525257325;12819.038572107036;14352.856471435362;1770.9026003569797;1791.3282519055354;1839.3654189304802;1879.2013394307364;1896.4784684211813;118560.99999999914;123924.14113788301;127231;127507.36246318802;128676;11292.774144517229;11482.712932176595;11485.310881346979;11517.539298428137;11755.823662645067;14347.067440616327;14368.353424095942;14375.28123593816;14392.280385980659;14404.42382304696;8187.836243275101;8243.505389676518;8300.584970751559;8431.957840210882;8443.155684431507;8093.190680931977;8119.350451963947;8121.228483294078;8250.339972802161;8313.293370063613;4416.542540961475;4421.579949904753;4428.734275943454;4535.229011068126;4631.907361852812;1733.5839398544258;1786.2764756881338;1847.879887807315;1909.675355189621;1920.0783623860345;730.1493759769809;805.6696754330696;866.4281574161023;913.2830727878584;929.8047410043966;19960.10179541924;21094;21129.78870211309;21168;21203.68194477084;883.4522595990398;890.7327801659605;895.2927187521915;919.5999740113073;921.589892497832;9885.209183265322;10569.966825212894;10847.566097356017;10953.288036277616;10998.395088270221;567584.162079187;568798;575084;588379;592046;74373.51252975018;76710.23289767142;101260.49369753104;129930.70069299375;131547;948.9905100948902;972.3193764364972;991.5736233419699;995.5619527407918;999.8050380175941;17992.91003544974;18559.25762969466;18638.627227455105;18727.43817685417;18730.25078996825;52495.47504525058;52594.47405525896;53253.934921302134;54773.1784023248;55538.44461555333;72044;72322;74147.88778168224;76904;78480;6020.608660437058;6785.117934668659;6936.618485983319;7211.026511420891;7355.080614923135;9020.278377729897;10618.141825180408;12098.75802483963;13024.54414095502;13036.934815325863;10859.131269498579;10889.183311251847;10906.236563440483;11029.08467494028;11092.168087393638;8880.467171969702;10633.096186824207;10780.460976951277;10809.459527023775;11208.943955280172;5732.627379220337;8269.973856405219;9442.300385930324;10934.453277335942;11208.55165793359;7734.419918506247;7805.180456052033;7898.447108702336;8166.959165204136;8227.506349619041;7929.841403171788;8122.472039317426;10234.283600147919;10710.946445267724;11024.669259922215 15:52:24 INFO - PROCESS | 1930 | |3;mootools.html;1284.287220592571;1462.5831637983176;1591.2998280756467;1785.8392744652979;1834.3213011185862;8040.758777236685;8476.576171191438;8479.109693482173;8495.36284778641;8509.319254459644;7395.778126656209;7664.425168112386;7688.500247483909;8013.959930200341;8237.711680091197;2325.848819826706;2359.634256690216;2363.0429675981254;2363.1847012780563;2366.9053237870467;2352.741198468167;2377.286813955815;2392.7726865947793;2396.9280921572295;2397.19693902542;2284.668723035159;2288.039023610088;2309.538092381522;2314.1206341590255;2319.721633403994;2071.9847274835374;2098.7061811346425;2148.903299351533;2177.878392627798;2341.906323747048;2351.5179388225374;2369.8459600126107;2370.8577485350934;2372.7627237276306;2375.5248950209852;8193.549354785484;8370.0374456937;8389.538575378352;8393.706220282262;8432.367572432093;5084.491550844923;5248.3701955765555;5255.894882102337;5262.342207224097;5265.605079619044;2654.5312859041082;3260.2175477885376;3494.9650503494895;3498;3500.0549851540195;1046.748780292732;1050.8791488978713;1050.9211809114347;1074.312440038376;1094.5129417409255;10986.121110311076;10998.560057597686;11110.555577776879;11175.44122793857;11217.719557011058;4535.7732113394195;4691.81232750693;4735.810567577328;4749.216379297377;4804.99095190007;9498.19265362452;9502.334836561513;9504;9512.714618561455;9517.095875891779;5156.329677141947;5156.329677141947;5156.690598564098;5169.638125331191;5171.4311425743135;4328.913421731548;4445.599896009375;4660.13787449323;4687.10944920464;4781.115493633689;3236.530703048045;3792.943105853359;3846.7114966377076;3873.2640798248244;3943.940840887389;1087.7933192693522;1256.3153081570565;1273.2169715624825;1288.5103660608909;1292.0632541407404;744.4937442539017;840.8948881389827;886.1227384888914;896.9506677132821;907.2469850024464;5589.413111623221;5593.972030139823;5625.296837895263;5830.7084645767545;5950.613210141413;849.9405041647026;863.2791618998082;917.9862302065475;945.3618807305179;961.3030552849268;3568.5725709715803;3651.634836516327;3708.9443658345144;3824.9235015299537;3832.7508711933633;9617.471039092918;10052.69841904744;10122.240831937634;10283.382997020202;10284.537195826206;9935.602575897028;9948.054934781328;10159.238057145743;10159.847602285972;10194.133498652698;7321.74373896911;7730.304272615492;7737.806554836174;7859.4891332063235;7869.803254918558;5663.915041274302;5703.258576385043;5813.709314534341;5830.53355731532;5848.53211743051;9254.167124958785;9279.62881484733;9380.67167649129;9559.952200238955;9568.712938611852;9311.72064838056;9488.715338539854;9510.762230944005;9679;9693.9030609693;7134.215236324104;7186.425085993213;7241.493095483265;7242.601656908921;7261.092363454568;7815.570143642154;7850.882236766567;7914.960425198067;7925.881111783443;7936.523808571505;6737.831554211184;7028.543144695578;7152.427805775629;7187.209406965125;7262.419006479363;5998.190244316961;6766.424853887278;6818.931810682028;7013.649317534205;7134.500584959003;6518.706658200297;6608.537402381787;6707;6710.194776626836;6714.664266786739;4263.445752052214;4791.4729379767505;4794;4798;4989.151844186425;4830.178869592248;4876.268559716071;4928.334674818997;4970.627202959865;5065.56942659878 15:52:24 INFO - PROCESS | 1930 | |4;prototype.html;301.6154403136001;303.1572229202816;310.2987248817672;315.9699828516293;316.11803069436274;4998.850034498962;5136.743162841862;5178.663386879854;5181.383401382306;5221.556167725738;4713.387259656249;4990;5088.694678319303;5090.291480380652;5210.2445145453985;3438.6561343865665;3956.485656864611;4004.5594984551676;4018.81444973732;4050.5544390117066;3390.7117894978833;3933.4099885017336;4050.8177132029127;4060.5433712603894;4077.697065377039;3346.3641908037393;3577.2130131371055;3647.0335361129323;3715.535558055243;4029.758214507124;4005.7997100144876;4018.8794336169963;4030.7581545107387;4031.294523458383;4084.4070812382897;3002.4445477586496;3395.015445520796;3555.6622120898473;3573.87491437801;3576.8032758198287;4562.680612357103;4883;5015.648904576681;5090.106068857327;5164.586833053347;3719.7724750832162;4016.7188296819236;4577.5880170784785;4586.839460618896;4635.596183625569;5426.321709786277;5443.760962227469;5460.924848557563;5481.780728770885;5503.229547863302;3341.465365541502;3446.5347178130883;3482.7387945903906;3678.393065144248;3909.843606255746;5620.555058549503;5682.289713785818;5701;5710.628809127435;5723.54211663066;3942.448057271984;3960.7127683502977;3961.4057891316534;3971.72197946141;3977.343738283209;3387.695737141218;3512.1570823002294;3906.277338692351;4020.190914088672;4067.9389809152885;4017.819198136091;4261.616454519108;4494.483134439516;4547.2269714148015;4587;5533.209751320358;5549.833504994856;5550.64097758451;5557.805476808292;5578.386377498472;1083.6809228251548;1233.864274929757;1271.4659842865833;1271.6630093025171;1310.88202061813;732.0775822463628;886.1714297132073;914.9268058555433;915.5879854065697;920.2178148573659;4428.02217007038;4487.528809475023;4493.539974219898;4869.780859861315;4973.452920178777;4875.609951203895;4972.328735620717;5057.949420505822;5091.676164197335;5360.6247562671015;4382.304882497241;4476.328550717353;4592.7725053349095;4822.12964905448;4901.3383193269165;2408.903643854226;2757.296889293217;2762;2823.5482322828248;2823.788215883817;5040.924386134211;5156.535911767884;5455.781768729285;5535.750891209906;5574;5445.727713614304;5507.504324610804;5526.6235518727335;5538.501534861882;5561.582881283839;4744.1934871072;5210.192420174879;5535.252740880011;5538.529225015919;5598.10430331145;4327.826886924488;4849.975750121227;5031.949680503149;5239.921401178909;5326.520613144836;5042.319286896222;5076.289319495199;5183.274341592105;5234.476552344735;5294.708791016531;4259.403683484253;4850.7574621269505;5171.5261150571905;5457.945420545744;5500.669959802424;3304.240024794263;3502.772319799205;3595.802230877327;3739.457778622139;3890.4164375343926;4951.282064100757;4964.882901347168;5563.499285064364;5711.371749107678;5737.148108928953;5519.613627046228;5578.525825304894;5579.497845193952;5622.690752008679;5623.325200975909;5161.7677204526635;5230.633855630222;5316.175992721134;5380.946190538045;5473.534749546333;4051.7974101294226;4858.684185527929;4874.5856602189215;5308.973455132854;5365.490278423544;4341.739495630272;5229.738513074407;5285.550728188147;5435.374931882726;5543.334799824047;4378;5406;5411.83764487066;5432.429594892637;5458.972705136449 15:52:24 INFO - PROCESS | 1930 | |5;yui.html;692.248909932723;744.300357663796;762.4967521435854;776.9689212431502;804.0712976512127;14060.648483787898;14199.361028753705;14614.123152610851;14684.853151468482;14919.92540037301;12935.870641293584;13194.53819116331;13314.93342533286;13318.067735258535;13330.066895317332;801.242825529873;823.5511646152831;826.8387664405443;826.89663792026;829.2495291760966;834.3783881008657;836.1680128272377;838.9664413423455;840.9747707568752;844.4679851693425;863.0765081362944;894.5482531321674;895.3553441522126;895.6238379880433;899.5502248875562;883.9204471597523;884.9026607073217;885.0839381240418;886.9024407315222;887.609451841191;650.8991106378471;652.8955367141234;658.3285049249738;659.363714015972;661.6592454885738;12407.75184496314;12723.936380318039;12734.936325318407;12740.235585864877;12773.425195866208;11188.832167517494;11382.829257561143;11471.426428678533;11569.42152892352;11678.824817627743;9724.562394692255;9829.606815727362;9962.302638815287;10054.849177262347;10292.433916134609;3472.878449254264;3478.478228265755;3520.331137083946;3521.2253304273017;3583.5878873929573;78819.6059019707;94310;96554;96667.51666241694;97421.5128924358;8449.53527555978;8556.401051926305;8827.955860220658;8863.778405539784;9005.279577633904;18140.365087221882;18267.451976440727;18422.539436513925;18668.34660786893;18687;7479.364254038468;7507.962460187664;7605.619719014027;7764.3788496920115;7804.921950780533;4004.1190937993597;4172;4257.340112282601;4279.550647182001;4370.628496577763;3361.5293858859777;3516.8065756383135;3517.472379143149;3523.3481805865995;3531.258435728526;630.4893036640336;630.5020223649767;641.6054126712038;651.3226244705548;651.6090345792582;530.9123994540857;550.6448340820228;556.5186114011432;649.5258461323166;655.5968079631087;8685.869711954325;8845.867311990325;9040.502772347583;9184.311176661777;9334.859977100348;523.8611767881514;599.1611743559052;652.3215855510311;653.3140202787055;678.4632806692845;3495.598006229265;4331.610155086058;4471.262241730143;4525.343825145335;4562.794674239667;87577.12422875817;89722.55138724265;109859.45070274598;118238;121142;72063.63968180126;86858;87158.56420717856;91574.08425915788;94233.28833558294;61361;76973.61513192399;77840.61079694565;82351.17648823555;84136.57931710302;9325.953370233105;9794.951025244829;9807.9019209807;9808.558614862492;9841.901580984384;33935;48399;51727.74136129295;56068.158977614556;56930.715346423;75560.62219688867;77572.83640745506;80279.59860200662;81369.33837977187;83317.58341208255;11113.944430277797;13073.803892941803;13350.198988060747;13788.379522921694;14432.783508247594;13817.170969741848;14157.150570965776;14413.351399186851;15094.094354338775;15419.074855508705;12754.170978886343;13423.395947182205;13769.862301376857;14310.642233944234;14426.278686065445;11371.260868043551;12877.549285774952;13477.258750768802;14500.782488262474;14669.339879705652;12591.496340146292;12789.104762666524;13537;13552;13742;10860.402677852479;11211.943940280573;11441.370724610226;11729.47217375203;12126.393680315772;8504.702335418231;10354;11396.373199474108;11565.479553419947;11600.709982250512 15:52:24 INFO - PROCESS | 1930 | __end_tp_report 15:52:24 INFO - PROCESS | 1930 | __start_cc_report 15:52:24 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1424 15:52:24 INFO - PROCESS | 1930 | __end_cc_report 15:52:24 INFO - PROCESS | 1930 | __startTimestamp1484005944616__endTimestamp 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | ------- Summary: start ------- 15:52:24 INFO - PROCESS | 1930 | Number of tests: 6 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5665.51 Median:5863.82 stddev:663.68 (11.3%) stddev-sans-first:629.67 15:52:24 INFO - PROCESS | 1930 | Values: 2915.4 3292.0 3320.9 3325.2 3381.7 4665.4 5576.9 5667.4 5670.6 5704.2 5576.3 5620.5 5629.9 5663.6 5664.3 5090.7 5108.0 5115.7 5177.5 5253.4 5097.8 5106.5 5120.3 5158.6 5241.3 5095.9 5182.5 5183.7 5197.4 5260.6 5189.7 5195.2 5212.3 5232.4 5260.9 4996.4 5141.0 5155.6 5191.9 5200.9 5573.7 5596.0 5598.3 5619.9 5645.7 6104.6 6132.1 6161.3 6213.7 6226.9 6284.2 6304.1 6308.0 6314.3 6321.7 5622.7 5641.3 5660.8 5679.7 5782.6 6362.5 6388.2 6406.1 6408.0 6416.8 6045.5 6054.5 6057.7 6060.2 6072.1 5903.5 5932.1 5958.8 6206.0 6224.7 5931.6 5932.9 5946.4 5948.9 5950.2 5909.3 5967.6 6104.2 6143.9 6211.6 4884.0 4900.5 4911.1 4929.4 4930.1 3668.5 4361.3 4370.5 4372.7 4403.4 5571.9 5627.9 5628.0 5652.1 5669.0 5176.2 6211.7 6272.4 6275.2 6293.0 5667.0 5708.8 5710.7 5713.1 5723.0 5237.4 5313.0 5373.3 5382.3 5397.0 6288.0 6347.8 6353.3 6372.7 6418.8 6087.4 6225.9 6238.6 6262.3 6266.2 6352.6 6388.8 6416.9 6420.1 6440.4 5939.9 5973.5 6098.2 6098.5 6133.1 6035.8 6159.6 6222.8 6227.0 6251.9 5201.0 5625.9 6020.7 6027.8 6044.3 5747.6 5905.0 5967.2 5973.3 5978.0 5896.0 5913.6 5916.6 5922.0 5925.8 6035.6 6046.1 6051.3 6060.4 6077.8 5696.9 5697.3 5808.9 5863.8 5875.1 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:68977.95 Median:39270.40 stddev:156033.08 (397.3%) stddev-sans-first:156385.97 15:52:24 INFO - PROCESS | 1930 | Values: 450.6 521.7 523.8 538.3 553.8 29850.6 38909.8 40263.6 40325.0 42096.8 40480.8 40578.6 40975.6 40995.6 41003.6 9312.7 9491.0 9505.1 9506.9 9511.0 8009.5 9514.9 9517.0 9528.8 9543.1 9459.5 9525.1 9532.5 9533.4 9540.7 9464.1 9483.4 9499.5 9504.6 9506.2 9372.8 9446.8 9447.5 9450.2 9453.9 38964.6 39248.0 39292.8 39292.8 39296.4 3262.1 3297.1 3357.7 3533.5 3818.9 28202.0 28400.7 29824.8 30557.7 30721.2 3114.3 3176.7 3178.1 3243.4 3287.0 486212.0 489103.0 489276.0 489391.0 503398.0 33375.0 33942.5 34382.1 58388.4 58923.1 35556.0 35650.5 35667.8 35680.1 35805.0 14117.0 14242.9 14247.7 14258.9 14287.4 11802.4 11858.4 11865.3 11865.8 11874.6 7079.7 7782.3 7804.7 7846.8 7849.0 2733.8 3266.3 3291.1 3303.2 3335.2 893.5 920.4 972.6 1025.7 1025.8 28227.0 29094.9 29163.3 29935.8 30009.5 856.2 911.3 932.2 932.3 1021.3 15120.4 15189.1 15281.7 15548.1 15600.5 671613.0 678939.0 679407.0 680303.0 681665.6 56746.4 57221.0 57246.0 57396.1 57688.0 1212.4 1248.5 1328.6 1350.2 1371.8 24113.0 28397.6 29703.9 30178.8 30996.1 48356.8 48915.0 48915.3 48959.0 49145.5 548423.0 551611.0 551965.0 552102.0 598069.0 39800.2 40032.6 40316.8 40343.2 40626.6 35820.1 44552.8 44653.8 44822.6 45578.3 36400.8 36419.8 36755.6 37724.0 38096.6 36310.5 36673.9 36787.4 37268.6 37800.0 35570.8 36011.5 36361.6 36431.0 36734.3 31211.8 33466.5 33641.5 33736.8 33848.7 24319.3 24764.1 25015.4 25305.8 25316.5 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:33287.42 Median:4425.16 stddev:96492.29 (2180.5%) stddev-sans-first:96731.23 15:52:24 INFO - PROCESS | 1930 | Values: 319.7 352.9 378.5 381.8 404.6 14862.9 15039.6 15897.5 20099.0 20501.9 19246.0 19674.6 19782.7 19891.4 19947.9 5653.2 5962.3 5964.2 5965.0 5972.6 5305.9 5523.1 5802.6 5861.2 5872.5 5782.7 5809.8 5817.2 5818.5 5827.1 5561.1 5850.8 5920.1 5953.3 5966.6 5532.8 5630.9 5632.9 5707.6 5753.6 12869.5 13091.4 13092.5 13115.5 17738.0 2152.7 2184.8 2265.1 2282.2 2320.5 8015.8 10250.4 10294.9 12819.0 14352.9 1770.9 1791.3 1839.4 1879.2 1896.5 118561.0 123924.1 127231.0 127507.4 128676.0 11292.8 11482.7 11485.3 11517.5 11755.8 14347.1 14368.4 14375.3 14392.3 14404.4 8187.8 8243.5 8300.6 8432.0 8443.2 8093.2 8119.4 8121.2 8250.3 8313.3 4416.5 4421.6 4428.7 4535.2 4631.9 1733.6 1786.3 1847.9 1909.7 1920.1 730.1 805.7 866.4 913.3 929.8 19960.1 21094.0 21129.8 21168.0 21203.7 883.5 890.7 895.3 919.6 921.6 9885.2 10570.0 10847.6 10953.3 10998.4 567584.2 568798.0 575084.0 588379.0 592046.0 74373.5 76710.2 101260.5 129930.7 131547.0 949.0 972.3 991.6 995.6 999.8 17992.9 18559.3 18638.6 18727.4 18730.3 52495.5 52594.5 53253.9 54773.2 55538.4 72044.0 72322.0 74147.9 76904.0 78480.0 6020.6 6785.1 6936.6 7211.0 7355.1 9020.3 10618.1 12098.8 13024.5 13036.9 10859.1 10889.2 10906.2 11029.1 11092.2 8880.5 10633.1 10780.5 10809.5 11208.9 5732.6 8270.0 9442.3 10934.5 11208.6 7734.4 7805.2 7898.4 8167.0 8227.5 7929.8 8122.5 10234.3 10710.9 11024.7 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5453.60 Median:5163.16 stddev:3002.57 (58.2%) stddev-sans-first:2994.64 15:52:24 INFO - PROCESS | 1930 | Values: 1284.3 1462.6 1591.3 1785.8 1834.3 8040.8 8476.6 8479.1 8495.4 8509.3 7395.8 7664.4 7688.5 8014.0 8237.7 2325.8 2359.6 2363.0 2363.2 2366.9 2352.7 2377.3 2392.8 2396.9 2397.2 2284.7 2288.0 2309.5 2314.1 2319.7 2072.0 2098.7 2148.9 2177.9 2341.9 2351.5 2369.8 2370.9 2372.8 2375.5 8193.5 8370.0 8389.5 8393.7 8432.4 5084.5 5248.4 5255.9 5262.3 5265.6 2654.5 3260.2 3495.0 3498.0 3500.1 1046.7 1050.9 1050.9 1074.3 1094.5 10986.1 10998.6 11110.6 11175.4 11217.7 4535.8 4691.8 4735.8 4749.2 4805.0 9498.2 9502.3 9504.0 9512.7 9517.1 5156.3 5156.3 5156.7 5169.6 5171.4 4328.9 4445.6 4660.1 4687.1 4781.1 3236.5 3792.9 3846.7 3873.3 3943.9 1087.8 1256.3 1273.2 1288.5 1292.1 744.5 840.9 886.1 897.0 907.2 5589.4 5594.0 5625.3 5830.7 5950.6 849.9 863.3 918.0 945.4 961.3 3568.6 3651.6 3708.9 3824.9 3832.8 9617.5 10052.7 10122.2 10283.4 10284.5 9935.6 9948.1 10159.2 10159.8 10194.1 7321.7 7730.3 7737.8 7859.5 7869.8 5663.9 5703.3 5813.7 5830.5 5848.5 9254.2 9279.6 9380.7 9560.0 9568.7 9311.7 9488.7 9510.8 9679.0 9693.9 7134.2 7186.4 7241.5 7242.6 7261.1 7815.6 7850.9 7915.0 7925.9 7936.5 6737.8 7028.5 7152.4 7187.2 7262.4 5998.2 6766.4 6818.9 7013.6 7134.5 6518.7 6608.5 6707.0 6710.2 6714.7 4263.4 4791.5 4794.0 4798.0 4989.2 4830.2 4876.3 4928.3 4970.6 5065.6 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4354.05 Median:4879.30 stddev:1326.03 (27.2%) stddev-sans-first:1294.40 15:52:24 INFO - PROCESS | 1930 | Values: 301.6 303.2 310.3 316.0 316.1 4998.9 5136.7 5178.7 5181.4 5221.6 4713.4 4990.0 5088.7 5090.3 5210.2 3438.7 3956.5 4004.6 4018.8 4050.6 3390.7 3933.4 4050.8 4060.5 4077.7 3346.4 3577.2 3647.0 3715.5 4029.8 4005.8 4018.9 4030.8 4031.3 4084.4 3002.4 3395.0 3555.7 3573.9 3576.8 4562.7 4883.0 5015.6 5090.1 5164.6 3719.8 4016.7 4577.6 4586.8 4635.6 5426.3 5443.8 5460.9 5481.8 5503.2 3341.5 3446.5 3482.7 3678.4 3909.8 5620.6 5682.3 5701.0 5710.6 5723.5 3942.4 3960.7 3961.4 3971.7 3977.3 3387.7 3512.2 3906.3 4020.2 4067.9 4017.8 4261.6 4494.5 4547.2 4587.0 5533.2 5549.8 5550.6 5557.8 5578.4 1083.7 1233.9 1271.5 1271.7 1310.9 732.1 886.2 914.9 915.6 920.2 4428.0 4487.5 4493.5 4869.8 4973.5 4875.6 4972.3 5057.9 5091.7 5360.6 4382.3 4476.3 4592.8 4822.1 4901.3 2408.9 2757.3 2762.0 2823.5 2823.8 5040.9 5156.5 5455.8 5535.8 5574.0 5445.7 5507.5 5526.6 5538.5 5561.6 4744.2 5210.2 5535.3 5538.5 5598.1 4327.8 4850.0 5031.9 5239.9 5326.5 5042.3 5076.3 5183.3 5234.5 5294.7 4259.4 4850.8 5171.5 5457.9 5500.7 3304.2 3502.8 3595.8 3739.5 3890.4 4951.3 4964.9 5563.5 5711.4 5737.1 5519.6 5578.5 5579.5 5622.7 5623.3 5161.8 5230.6 5316.2 5380.9 5473.5 4051.8 4858.7 4874.6 5309.0 5365.5 4341.7 5229.7 5285.6 5435.4 5543.3 4378.0 5406.0 5411.8 5432.4 5459.0 15:52:24 INFO - PROCESS | 1930 | 15:52:24 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:19939.39 Median:28312.34 stddev:29236.76 (103.3%) stddev-sans-first:29283.05 15:52:24 INFO - PROCESS | 1930 | Values: 692.2 744.3 762.5 777.0 804.1 14060.6 14199.4 14614.1 14684.9 14919.9 12935.9 13194.5 13314.9 13318.1 13330.1 801.2 823.6 826.8 826.9 829.2 834.4 836.2 839.0 841.0 844.5 863.1 894.5 895.4 895.6 899.6 883.9 884.9 885.1 886.9 887.6 650.9 652.9 658.3 659.4 661.7 12407.8 12723.9 12734.9 12740.2 12773.4 11188.8 11382.8 11471.4 11569.4 11678.8 9724.6 9829.6 9962.3 10054.8 10292.4 3472.9 3478.5 3520.3 3521.2 3583.6 78819.6 94310.0 96554.0 96667.5 97421.5 8449.5 8556.4 8828.0 8863.8 9005.3 18140.4 18267.5 18422.5 18668.3 18687.0 7479.4 7508.0 7605.6 7764.4 7804.9 4004.1 4172.0 4257.3 4279.6 4370.6 3361.5 3516.8 3517.5 3523.3 3531.3 630.5 630.5 641.6 651.3 651.6 530.9 550.6 556.5 649.5 655.6 8685.9 8845.9 9040.5 9184.3 9334.9 523.9 599.2 652.3 653.3 678.5 3495.6 4331.6 4471.3 4525.3 4562.8 87577.1 89722.6 109859.5 118238.0 121142.0 72063.6 86858.0 87158.6 91574.1 94233.3 61361.0 76973.6 77840.6 82351.2 84136.6 9326.0 9795.0 9807.9 9808.6 9841.9 33935.0 48399.0 51727.7 56068.2 56930.7 75560.6 77572.8 80279.6 81369.3 83317.6 11113.9 13073.8 13350.2 13788.4 14432.8 13817.2 14157.2 14413.4 15094.1 15419.1 12754.2 13423.4 13769.9 14310.6 14426.3 11371.3 12877.5 13477.3 14500.8 14669.3 12591.5 12789.1 13537.0 13552.0 13742.0 10860.4 11211.9 11441.4 11729.5 12126.4 8504.7 10354.0 11396.4 11565.5 11600.7 15:52:24 INFO - PROCESS | 1930 | -------- Summary: end -------- 15:52:24 INFO - PROCESS | 1930 | 15:52:25 INFO - TEST-INFO | 1930: exit 0 15:52:25 INFO - TEST-OK | dromaeo_css | took 1119558ms 15:52:25 INFO - TEST-START | kraken 15:52:25 INFO - operating with platform_type : mac_ 15:52:25 INFO - Initialising browser for kraken test... 15:52:25 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpphvU5c/profile http://localhost:54567/getInfo.html) 15:52:28 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 15:52:28 INFO - PROCESS | 1935 | colorDepth:24 15:52:28 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 15:52:28 INFO - PROCESS | 1935 | __metrics 15:52:28 INFO - PROCESS | 1935 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:52:28 INFO - PROCESS | 1935 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:29 INFO - TEST-INFO | 1935: exit 0 15:52:29 INFO - Browser initialized. 15:52:29 INFO - Running cycle 1/1 for kraken test... 15:52:29 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpphvU5c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:41 INFO - PROCESS | 1936 | RSS: Main: 223244288 15:52:41 INFO - PROCESS | 1936 | 15:52:44 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54567/tests/kraken/driver.html?testName=audio-beat-detection) 15:52:45 INFO - PROCESS | 1936 | RSS: Main: 270786560 15:52:45 INFO - PROCESS | 1936 | 15:52:48 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54567/tests/kraken/driver.html?testName=audio-dft) 15:52:48 INFO - PROCESS | 1936 | RSS: Main: 284397568 15:52:48 INFO - PROCESS | 1936 | 15:52:52 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54567/tests/kraken/driver.html?testName=audio-fft) 15:52:52 INFO - PROCESS | 1936 | RSS: Main: 381923328 15:52:52 INFO - PROCESS | 1936 | 15:52:54 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54567/tests/kraken/driver.html?testName=audio-oscillator) 15:52:55 INFO - PROCESS | 1936 | RSS: Main: 284905472 15:52:55 INFO - PROCESS | 1936 | 15:52:57 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54567/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:52:57 INFO - PROCESS | 1936 | RSS: Main: 285913088 15:52:57 INFO - PROCESS | 1936 | 15:53:00 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54567/tests/kraken/driver.html?testName=imaging-darkroom) 15:53:01 INFO - PROCESS | 1936 | RSS: Main: 326643712 15:53:01 INFO - PROCESS | 1936 | 15:53:04 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54567/tests/kraken/driver.html?testName=imaging-desaturate) 15:53:04 INFO - PROCESS | 1936 | RSS: Main: 326721536 15:53:04 INFO - PROCESS | 1936 | 15:53:07 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54567/tests/kraken/driver.html?testName=json-parse-financial) 15:53:08 INFO - PROCESS | 1936 | RSS: Main: 325496832 15:53:08 INFO - PROCESS | 1936 | 15:53:09 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54567/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:53:10 INFO - PROCESS | 1936 | RSS: Main: 300732416 15:53:10 INFO - PROCESS | 1936 | 15:53:12 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:53:13 INFO - PROCESS | 1936 | RSS: Main: 301314048 15:53:13 INFO - PROCESS | 1936 | 15:53:15 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:53:16 INFO - PROCESS | 1936 | RSS: Main: 303960064 15:53:16 INFO - PROCESS | 1936 | 15:53:19 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:53:19 INFO - PROCESS | 1936 | RSS: Main: 323010560 15:53:19 INFO - PROCESS | 1936 | 15:53:22 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:53:23 INFO - PROCESS | 1936 | RSS: Main: 316334080 15:53:23 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:54567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54567/tests/kraken/driver.html?testName=ai-astar) 15:53:25 INFO - PROCESS | 1936 | RSS: Main: 360222720 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | __start_tp_report 15:53:25 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 15:53:25 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 15:53:25 INFO - PROCESS | 1936 | |i|pagename|runs| 15:53:25 INFO - PROCESS | 1936 | |0;ai-astar;105;119;133;115;116;110;92;130;170;173 15:53:25 INFO - PROCESS | 1936 | |1;audio-beat-detection;160;143;145;162;128;140;137;109;140;119 15:53:25 INFO - PROCESS | 1936 | |2;audio-dft;186;184;184;185;184;188;184;185;185;194 15:53:25 INFO - PROCESS | 1936 | |3;audio-fft;80;60;62;114;87;81;59;58;83;57 15:53:25 INFO - PROCESS | 1936 | |4;audio-oscillator;84;73;95;70;68;68;89;68;68;93 15:53:25 INFO - PROCESS | 1936 | |5;imaging-gaussian-blur;87;85;88;104;135;85;88;84;89;87 15:53:25 INFO - PROCESS | 1936 | |6;imaging-darkroom;92;93;94;94;98;96;93;93;93;93 15:53:25 INFO - PROCESS | 1936 | |7;imaging-desaturate;85;81;83;81;88;122;128;84;82;83 15:53:25 INFO - PROCESS | 1936 | |8;json-parse-financial;55;54;53;55;57;58;59;58;57;57 15:53:25 INFO - PROCESS | 1936 | |9;json-stringify-tinderbox;54;72;103;102;104;71;69;70;71;71 15:53:25 INFO - PROCESS | 1936 | |10;stanford-crypto-aes;83;84;83;86;83;85;92;100;102;100 15:53:25 INFO - PROCESS | 1936 | |11;stanford-crypto-ccm;144;151;148;142;143;140;144;155;213;160 15:53:25 INFO - PROCESS | 1936 | |12;stanford-crypto-pbkdf2;167;163;173;219;198;162;220;163;165;163 15:53:25 INFO - PROCESS | 1936 | |13;stanford-crypto-sha256-iterative;67;69;68;69;68;66;70;62;83;69 15:53:25 INFO - PROCESS | 1936 | __end_tp_report 15:53:25 INFO - PROCESS | 1936 | __start_cc_report 15:53:25 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,2050 15:53:25 INFO - PROCESS | 1936 | __end_cc_report 15:53:25 INFO - PROCESS | 1936 | __startTimestamp1484006005509__endTimestamp 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | ------- Summary: start ------- 15:53:25 INFO - PROCESS | 1936 | Number of tests: 14 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#0] ai-astar Cycles:10 Average:126.30 Median:131.50 stddev:26.52 (20.2%) stddev-sans-first:26.99 15:53:25 INFO - PROCESS | 1936 | Values: 105.0 119.0 133.0 115.0 116.0 110.0 92.0 130.0 170.0 173.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:141.50 stddev:16.48 (11.6%) stddev-sans-first:15.50 15:53:25 INFO - PROCESS | 1936 | Values: 160.0 143.0 145.0 162.0 128.0 140.0 137.0 109.0 140.0 119.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#2] audio-dft Cycles:10 Average:185.90 Median:185.00 stddev:3.11 (1.7%) stddev-sans-first:3.30 15:53:25 INFO - PROCESS | 1936 | Values: 186.0 184.0 184.0 185.0 184.0 188.0 184.0 185.0 185.0 194.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#3] audio-fft Cycles:10 Average:74.10 Median:71.00 stddev:18.39 (25.9%) stddev-sans-first:19.38 15:53:25 INFO - PROCESS | 1936 | Values: 80.0 60.0 62.0 114.0 87.0 81.0 59.0 58.0 83.0 57.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:11.35 (14.5%) stddev-sans-first:11.79 15:53:25 INFO - PROCESS | 1936 | Values: 84.0 73.0 95.0 70.0 68.0 68.0 89.0 68.0 68.0 93.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:87.00 stddev:15.75 (18.1%) stddev-sans-first:16.54 15:53:25 INFO - PROCESS | 1936 | Values: 87.0 85.0 88.0 104.0 135.0 85.0 88.0 84.0 89.0 87.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:93.50 stddev:1.79 (1.9%) stddev-sans-first:1.76 15:53:25 INFO - PROCESS | 1936 | Values: 92.0 93.0 94.0 94.0 98.0 96.0 93.0 93.0 93.0 93.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:83.00 stddev:17.73 (21.4%) stddev-sans-first:18.64 15:53:25 INFO - PROCESS | 1936 | Values: 85.0 81.0 83.0 81.0 88.0 122.0 128.0 84.0 82.0 83.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.95 (3.4%) stddev-sans-first:2.01 15:53:25 INFO - PROCESS | 1936 | Values: 55.0 54.0 53.0 55.0 57.0 58.0 59.0 58.0 57.0 57.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:70.50 stddev:17.56 (24.9%) stddev-sans-first:16.19 15:53:25 INFO - PROCESS | 1936 | Values: 54.0 72.0 103.0 102.0 104.0 71.0 69.0 70.0 71.0 71.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:83.50 stddev:7.97 (9.5%) stddev-sans-first:8.06 15:53:25 INFO - PROCESS | 1936 | Values: 83.0 84.0 83.0 86.0 83.0 85.0 92.0 100.0 102.0 100.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:149.50 stddev:21.66 (14.5%) stddev-sans-first:22.67 15:53:25 INFO - PROCESS | 1936 | Values: 144.0 151.0 148.0 142.0 143.0 140.0 144.0 155.0 213.0 160.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.30 Median:170.00 stddev:23.74 (14.0%) stddev-sans-first:24.76 15:53:25 INFO - PROCESS | 1936 | Values: 167.0 163.0 173.0 219.0 198.0 162.0 220.0 163.0 165.0 163.0 15:53:25 INFO - PROCESS | 1936 | 15:53:25 INFO - PROCESS | 1936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:5.38 (7.8%) stddev-sans-first:5.66 15:53:25 INFO - PROCESS | 1936 | Values: 67.0 69.0 68.0 69.0 68.0 66.0 70.0 62.0 83.0 69.0 15:53:25 INFO - PROCESS | 1936 | -------- Summary: end -------- 15:53:25 INFO - PROCESS | 1936 | 15:53:26 INFO - TEST-INFO | 1936: exit 0 15:53:26 INFO - TEST-OK | kraken | took 61477ms 15:53:26 INFO - SUITE-END | took 1181s 15:53:27 INFO - Completed test suite (00:19:41) 15:53:27 INFO - javascript benchmark 15:53:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2915.402342519783, 3291.9670803291974, 3320.850561724722, 3325.2185736351967, 3381.7294616430686, 4665.393498845149, 5576.944230557693, 5667.404922483137, 5670.631408958415, 5704.229928959594, 5576.33084029917, 5620.494155526002, 5629.887402251952, 5663.603547751659, 5664.348599911012, 5090.669106508074, 5108.029474399871, 5115.718635475047, 5177.456367081459, 5253.369595648527, 5097.821576244833, 5106.463821298766, 5120.334356533645, 5158.638895277332, 5241.318628578299, 5095.898082038357, 5182.455842136578, 5183.688978661274, 5197.350331208599, 5260.579153667716, 5189.688618682872, 5195.246689230078, 5212.296339994128, 5232.372115346145, 5260.947390526084, 4996.400431948153, 5140.974295128538, 5155.587552995751, 5191.922121168185, 5200.881810410742, 5573.749181286811, 5595.972020139873, 5598.328200615912, 5619.943800561983, 5645.717714114277, 6104.572679912408, 6132.080187971796, 6161.260648722137, 6213.658248796314, 6226.875462490725, 6284.151639528696, 6304.117423560705, 6307.96846015767, 6314.273858506284, 6321.74713011479, 5622.690752008597, 5641.266635337462, 5660.801871934463, 5679.6876171810945, 5782.624129431573, 6362.491000719931, 6388.201474815648, 6406.103145559626, 6408.03879418091, 6416.807495775134, 6045.546584006217, 6054.455099041107, 6057.666828324484, 6060.2424696912885, 6072.149899014142, 5903.498202652737, 5932.080527518242, 5958.761649534057, 6205.968970155211, 6224.719887605069, 5931.614445061057, 5932.881342373128, 5946.43508866657, 5948.851278718067, 5950.226470558886, 5909.261342332208, 5967.582269241111, 6104.236970378703, 6143.894099062211, 6211.627302361872, 4884.0, 4900.5099490051425, 4911.115999120121, 4929.433115191727, 4930.137225985438, 3668.523091998023, 4361.280388735887, 4370.475542934804, 4372.6939114261695, 4403.405540252025, 5571.916421253684, 5627.943720562742, 5627.971860140673, 5652.0674088774895, 5669.0, 5176.249443830623, 6211.689415529206, 6272.435480806749, 6275.152854364694, 6292.968535157386, 5667.036603777367, 5708.800191993343, 5710.7430165642645, 5713.085906255063, 5723.0, 5237.423883372826, 5313.0436521425745, 5373.328333958255, 5382.327209098863, 5397.0, 6288.025356069817, 6347.777827776005, 6353.269374021862, 6372.74509019634, 6418.839529011812, 6087.421694938974, 6225.875482490234, 6238.625682459067, 6262.34245404226, 6266.248050234001, 6352.645361982474, 6388.8083357499345, 6416.903746443713, 6420.101185833893, 6440.420362167428, 5939.851503712442, 5973.522118230444, 6098.176746139215, 6098.481629061579, 6133.080037994337, 6035.849103772265, 6159.5688301818445, 6222.80135477234, 6227.034809604518, 6251.9062214067735, 5200.973995130152, 5625.915611265752, 6020.668863212565, 6027.78902738402, 6044.335123136363, 5747.597668163188, 5905.0, 5967.194428752063, 5973.253343332083, 5978.0, 5896.0, 5913.645181289136, 5916.55625828056, 5921.970390148022, 5925.792597259076, 6035.637861728134, 6046.062860256668, 6051.334353221054, 6060.393960603904, 6077.848053798691, 5696.886062278814, 5697.316322041382, 5808.883822323445, 5863.824085277449, 5875.089361149029], "value": 5621.309549543082, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.6057199950044, 521.7052365413541, 523.7695414017832, 538.3378444513248, 553.765103818485, 29850.552241716363, 38909.83270501886, 40263.59736402635, 40325.0, 42096.78951605246, 40480.79759601205, 40578.59421405785, 40975.59024409755, 40995.59004409955, 41003.58996410035, 9312.720618381425, 9491.0, 9505.14453699167, 9506.857397139049, 9510.952445237765, 8009.519428834259, 9514.904850951505, 9517.000714924938, 9528.809423811519, 9543.141117299441, 9459.479728614924, 9525.142737153656, 9532.523373831315, 9533.427994320364, 9540.713778586654, 9464.053594640482, 9483.383580067308, 9499.477528735917, 9504.572294246705, 9506.19197368228, 9372.812543749154, 9446.811063778687, 9447.480388578626, 9450.243980481546, 9453.858192127123, 38964.61035389638, 39248.0, 39292.80353598214, 39292.80353598242, 39296.41055384143, 3262.1029216965285, 3297.1262615406936, 3357.7481688873186, 3533.5406397168204, 3818.9045273868096, 28202.0, 28400.715992840014, 29824.807007719664, 30557.694423055706, 30721.231969200726, 3114.283714745621, 3176.7140957313954, 3178.078357276387, 3243.448613735647, 3287.0, 486212.0, 489103.0, 489275.99999999645, 489391.0, 503398.0, 33375.0, 33942.49086263708, 34382.14044648904, 58388.4161158383, 58923.11615325774, 35556.0, 35650.465243021375, 35667.82166089153, 35680.107997299754, 35805.0, 14117.0, 14242.931780116533, 14247.715045699028, 14258.928705356408, 14287.357068931922, 11802.350870702023, 11858.407079645984, 11865.288082715064, 11865.822012669816, 11874.643760687193, 7079.725649383057, 7782.260685234894, 7804.726834560764, 7846.8430631387055, 7849.0, 2733.8223015503927, 3266.3140740444296, 3291.078498020558, 3303.2072302647193, 3335.216224187314, 893.4728510179026, 920.3741455810114, 972.6060945317016, 1025.6820385680462, 1025.7640742629237, 28227.0, 29094.85452572766, 29163.27091822679, 29935.80256789704, 30009.549856752164, 856.2465030773004, 911.2618778789202, 932.1936524905908, 932.342698397628, 1021.3361315145215, 15120.395184192728, 15189.088654680754, 15281.694366112615, 15548.067115973077, 15600.453984110502, 671613.0, 678939.0, 679407.0, 680303.0, 681665.5916720384, 56746.43253567494, 57221.0, 57246.0, 57396.139057914166, 57688.0, 1212.3938030984507, 1248.525560287085, 1328.6147017365242, 1350.2371160294401, 1371.8490965993733, 24113.0, 28397.574036389058, 29703.851480742458, 30178.849105754332, 30996.0701178965, 48356.75821620869, 48915.0216995651, 48915.266271005254, 48959.0, 49145.50854491409, 548423.0, 551611.0, 551965.0, 552102.0, 598069.009654949, 39800.2039959205, 40032.599674002886, 40316.79841600773, 40343.1931361377, 40626.593734062284, 35820.10449738777, 44552.77723611361, 44653.77673111744, 44822.551774481835, 45578.31632525581, 36400.81799590985, 36419.817900910326, 36755.63244367522, 37724.0, 38096.61903381041, 36310.455343170404, 36673.89978300655, 36787.44818827773, 37268.6273137276, 37800.0, 35570.822145889106, 36011.45982810312, 36361.636383636884, 36431.0, 36734.265314694094, 31211.843940780152, 33466.49800253047, 33641.495377569845, 33736.831315843265, 33848.66151338455, 24319.270421886664, 24764.133255335975, 25015.374615634755, 25305.797462055238, 25316.493670126863], "value": 18300.231288997395, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.742607201203, 352.8906039127869, 378.479590562976, 381.7709374375375, 404.6156151655926, 14862.925685371572, 15039.624009399771, 15897.52307430776, 20099.0, 20501.897490512525, 19246.03769811511, 19674.606507869834, 19782.70325945112, 19891.40325790225, 19947.900260498714, 5653.180288858113, 5962.284525856881, 5964.194833697438, 5965.045592705146, 5972.611780234292, 5305.885763989567, 5523.1163013917785, 5802.564807639423, 5861.238039054917, 5872.530197584183, 5782.681952492611, 5809.82570522885, 5817.214676018747, 5818.534517238632, 5827.096799996008, 5561.082421400464, 5850.824475265749, 5920.111983202511, 5953.2856057272975, 5966.642001479881, 5532.7786888524415, 5630.859228519279, 5632.943670563324, 5707.62900411476, 5753.597248192628, 12869.549565765154, 13091.41088651013, 13092.476300948047, 13115.475380984844, 17738.0, 2152.7416709994745, 2184.803367696915, 2265.127925748582, 2282.1898226129815, 2320.5474932388192, 8015.839683206362, 10250.38497690141, 10294.948525257325, 12819.038572107036, 14352.856471435362, 1770.9026003569797, 1791.3282519055354, 1839.3654189304802, 1879.2013394307364, 1896.4784684211813, 118560.99999999914, 123924.14113788301, 127231.0, 127507.36246318802, 128676.0, 11292.774144517229, 11482.712932176595, 11485.310881346979, 11517.539298428137, 11755.823662645067, 14347.067440616327, 14368.353424095942, 14375.28123593816, 14392.280385980659, 14404.42382304696, 8187.836243275101, 8243.505389676518, 8300.584970751559, 8431.957840210882, 8443.155684431507, 8093.190680931977, 8119.350451963947, 8121.228483294078, 8250.339972802161, 8313.293370063613, 4416.542540961475, 4421.579949904753, 4428.734275943454, 4535.229011068126, 4631.907361852812, 1733.5839398544258, 1786.2764756881338, 1847.879887807315, 1909.675355189621, 1920.0783623860345, 730.1493759769809, 805.6696754330696, 866.4281574161023, 913.2830727878584, 929.8047410043966, 19960.10179541924, 21094.0, 21129.78870211309, 21168.0, 21203.68194477084, 883.4522595990398, 890.7327801659605, 895.2927187521915, 919.5999740113073, 921.589892497832, 9885.209183265322, 10569.966825212894, 10847.566097356017, 10953.288036277616, 10998.395088270221, 567584.162079187, 568798.0, 575084.0, 588379.0, 592046.0, 74373.51252975018, 76710.23289767142, 101260.49369753104, 129930.70069299375, 131547.0, 948.9905100948902, 972.3193764364972, 991.5736233419699, 995.5619527407918, 999.8050380175941, 17992.91003544974, 18559.25762969466, 18638.627227455105, 18727.43817685417, 18730.25078996825, 52495.47504525058, 52594.47405525896, 53253.934921302134, 54773.1784023248, 55538.44461555333, 72044.0, 72322.0, 74147.88778168224, 76904.0, 78480.0, 6020.608660437058, 6785.117934668659, 6936.618485983319, 7211.026511420891, 7355.080614923135, 9020.278377729897, 10618.141825180408, 12098.75802483963, 13024.54414095502, 13036.934815325863, 10859.131269498579, 10889.183311251847, 10906.236563440483, 11029.08467494028, 11092.168087393638, 8880.467171969702, 10633.096186824207, 10780.460976951277, 10809.459527023775, 11208.943955280172, 5732.627379220337, 8269.973856405219, 9442.300385930324, 10934.453277335942, 11208.55165793359, 7734.419918506247, 7805.180456052033, 7898.447108702336, 8166.959165204136, 8227.506349619041, 7929.841403171788, 8122.472039317426, 10234.283600147919, 10710.946445267724, 11024.669259922215], "value": 9058.1901170331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.287220592571, 1462.5831637983176, 1591.2998280756467, 1785.8392744652979, 1834.3213011185862, 8040.758777236685, 8476.576171191438, 8479.109693482173, 8495.36284778641, 8509.319254459644, 7395.778126656209, 7664.425168112386, 7688.500247483909, 8013.959930200341, 8237.711680091197, 2325.848819826706, 2359.634256690216, 2363.0429675981254, 2363.1847012780563, 2366.9053237870467, 2352.741198468167, 2377.286813955815, 2392.7726865947793, 2396.9280921572295, 2397.19693902542, 2284.668723035159, 2288.039023610088, 2309.538092381522, 2314.1206341590255, 2319.721633403994, 2071.9847274835374, 2098.7061811346425, 2148.903299351533, 2177.878392627798, 2341.906323747048, 2351.5179388225374, 2369.8459600126107, 2370.8577485350934, 2372.7627237276306, 2375.5248950209852, 8193.549354785484, 8370.0374456937, 8389.538575378352, 8393.706220282262, 8432.367572432093, 5084.491550844923, 5248.3701955765555, 5255.894882102337, 5262.342207224097, 5265.605079619044, 2654.5312859041082, 3260.2175477885376, 3494.9650503494895, 3498.0, 3500.0549851540195, 1046.748780292732, 1050.8791488978713, 1050.9211809114347, 1074.312440038376, 1094.5129417409255, 10986.121110311076, 10998.560057597686, 11110.555577776879, 11175.44122793857, 11217.719557011058, 4535.7732113394195, 4691.81232750693, 4735.810567577328, 4749.216379297377, 4804.99095190007, 9498.19265362452, 9502.334836561513, 9504.0, 9512.714618561455, 9517.095875891779, 5156.329677141947, 5156.329677141947, 5156.690598564098, 5169.638125331191, 5171.4311425743135, 4328.913421731548, 4445.599896009375, 4660.13787449323, 4687.10944920464, 4781.115493633689, 3236.530703048045, 3792.943105853359, 3846.7114966377076, 3873.2640798248244, 3943.940840887389, 1087.7933192693522, 1256.3153081570565, 1273.2169715624825, 1288.5103660608909, 1292.0632541407404, 744.4937442539017, 840.8948881389827, 886.1227384888914, 896.9506677132821, 907.2469850024464, 5589.413111623221, 5593.972030139823, 5625.296837895263, 5830.7084645767545, 5950.613210141413, 849.9405041647026, 863.2791618998082, 917.9862302065475, 945.3618807305179, 961.3030552849268, 3568.5725709715803, 3651.634836516327, 3708.9443658345144, 3824.9235015299537, 3832.7508711933633, 9617.471039092918, 10052.69841904744, 10122.240831937634, 10283.382997020202, 10284.537195826206, 9935.602575897028, 9948.054934781328, 10159.238057145743, 10159.847602285972, 10194.133498652698, 7321.74373896911, 7730.304272615492, 7737.806554836174, 7859.4891332063235, 7869.803254918558, 5663.915041274302, 5703.258576385043, 5813.709314534341, 5830.53355731532, 5848.53211743051, 9254.167124958785, 9279.62881484733, 9380.67167649129, 9559.952200238955, 9568.712938611852, 9311.72064838056, 9488.715338539854, 9510.762230944005, 9679.0, 9693.9030609693, 7134.215236324104, 7186.425085993213, 7241.493095483265, 7242.601656908921, 7261.092363454568, 7815.570143642154, 7850.882236766567, 7914.960425198067, 7925.881111783443, 7936.523808571505, 6737.831554211184, 7028.543144695578, 7152.427805775629, 7187.209406965125, 7262.419006479363, 5998.190244316961, 6766.424853887278, 6818.931810682028, 7013.649317534205, 7134.500584959003, 6518.706658200297, 6608.537402381787, 6707.0, 6710.194776626836, 6714.664266786739, 4263.445752052214, 4791.4729379767505, 4794.0, 4798.0, 4989.151844186425, 4830.178869592248, 4876.268559716071, 4928.334674818997, 4970.627202959865, 5065.56942659878], "value": 4412.677502990244, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.6154403136001, 303.1572229202816, 310.2987248817672, 315.9699828516293, 316.11803069436274, 4998.850034498962, 5136.743162841862, 5178.663386879854, 5181.383401382306, 5221.556167725738, 4713.387259656249, 4990.0, 5088.694678319303, 5090.291480380652, 5210.2445145453985, 3438.6561343865665, 3956.485656864611, 4004.5594984551676, 4018.81444973732, 4050.5544390117066, 3390.7117894978833, 3933.4099885017336, 4050.8177132029127, 4060.5433712603894, 4077.697065377039, 3346.3641908037393, 3577.2130131371055, 3647.0335361129323, 3715.535558055243, 4029.758214507124, 4005.7997100144876, 4018.8794336169963, 4030.7581545107387, 4031.294523458383, 4084.4070812382897, 3002.4445477586496, 3395.015445520796, 3555.6622120898473, 3573.87491437801, 3576.8032758198287, 4562.680612357103, 4883.0, 5015.648904576681, 5090.106068857327, 5164.586833053347, 3719.7724750832162, 4016.7188296819236, 4577.5880170784785, 4586.839460618896, 4635.596183625569, 5426.321709786277, 5443.760962227469, 5460.924848557563, 5481.780728770885, 5503.229547863302, 3341.465365541502, 3446.5347178130883, 3482.7387945903906, 3678.393065144248, 3909.843606255746, 5620.555058549503, 5682.289713785818, 5701.0, 5710.628809127435, 5723.54211663066, 3942.448057271984, 3960.7127683502977, 3961.4057891316534, 3971.72197946141, 3977.343738283209, 3387.695737141218, 3512.1570823002294, 3906.277338692351, 4020.190914088672, 4067.9389809152885, 4017.819198136091, 4261.616454519108, 4494.483134439516, 4547.2269714148015, 4587.0, 5533.209751320358, 5549.833504994856, 5550.64097758451, 5557.805476808292, 5578.386377498472, 1083.6809228251548, 1233.864274929757, 1271.4659842865833, 1271.6630093025171, 1310.88202061813, 732.0775822463628, 886.1714297132073, 914.9268058555433, 915.5879854065697, 920.2178148573659, 4428.02217007038, 4487.528809475023, 4493.539974219898, 4869.780859861315, 4973.452920178777, 4875.609951203895, 4972.328735620717, 5057.949420505822, 5091.676164197335, 5360.6247562671015, 4382.304882497241, 4476.328550717353, 4592.7725053349095, 4822.12964905448, 4901.3383193269165, 2408.903643854226, 2757.296889293217, 2762.0, 2823.5482322828248, 2823.788215883817, 5040.924386134211, 5156.535911767884, 5455.781768729285, 5535.750891209906, 5574.0, 5445.727713614304, 5507.504324610804, 5526.6235518727335, 5538.501534861882, 5561.582881283839, 4744.1934871072, 5210.192420174879, 5535.252740880011, 5538.529225015919, 5598.10430331145, 4327.826886924488, 4849.975750121227, 5031.949680503149, 5239.921401178909, 5326.520613144836, 5042.319286896222, 5076.289319495199, 5183.274341592105, 5234.476552344735, 5294.708791016531, 4259.403683484253, 4850.7574621269505, 5171.5261150571905, 5457.945420545744, 5500.669959802424, 3304.240024794263, 3502.772319799205, 3595.802230877327, 3739.457778622139, 3890.4164375343926, 4951.282064100757, 4964.882901347168, 5563.499285064364, 5711.371749107678, 5737.148108928953, 5519.613627046228, 5578.525825304894, 5579.497845193952, 5622.690752008679, 5623.325200975909, 5161.7677204526635, 5230.633855630222, 5316.175992721134, 5380.946190538045, 5473.534749546333, 4051.7974101294226, 4858.684185527929, 4874.5856602189215, 5308.973455132854, 5365.490278423544, 4341.739495630272, 5229.738513074407, 5285.550728188147, 5435.374931882726, 5543.334799824047, 4378.0, 5406.0, 5411.83764487066, 5432.429594892637, 5458.972705136449], "value": 3935.1002645023696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.248909932723, 744.300357663796, 762.4967521435854, 776.9689212431502, 804.0712976512127, 14060.648483787898, 14199.361028753705, 14614.123152610851, 14684.853151468482, 14919.92540037301, 12935.870641293584, 13194.53819116331, 13314.93342533286, 13318.067735258535, 13330.066895317332, 801.242825529873, 823.5511646152831, 826.8387664405443, 826.89663792026, 829.2495291760966, 834.3783881008657, 836.1680128272377, 838.9664413423455, 840.9747707568752, 844.4679851693425, 863.0765081362944, 894.5482531321674, 895.3553441522126, 895.6238379880433, 899.5502248875562, 883.9204471597523, 884.9026607073217, 885.0839381240418, 886.9024407315222, 887.609451841191, 650.8991106378471, 652.8955367141234, 658.3285049249738, 659.363714015972, 661.6592454885738, 12407.75184496314, 12723.936380318039, 12734.936325318407, 12740.235585864877, 12773.425195866208, 11188.832167517494, 11382.829257561143, 11471.426428678533, 11569.42152892352, 11678.824817627743, 9724.562394692255, 9829.606815727362, 9962.302638815287, 10054.849177262347, 10292.433916134609, 3472.878449254264, 3478.478228265755, 3520.331137083946, 3521.2253304273017, 3583.5878873929573, 78819.6059019707, 94310.0, 96554.0, 96667.51666241694, 97421.5128924358, 8449.53527555978, 8556.401051926305, 8827.955860220658, 8863.778405539784, 9005.279577633904, 18140.365087221882, 18267.451976440727, 18422.539436513925, 18668.34660786893, 18687.0, 7479.364254038468, 7507.962460187664, 7605.619719014027, 7764.3788496920115, 7804.921950780533, 4004.1190937993597, 4172.0, 4257.340112282601, 4279.550647182001, 4370.628496577763, 3361.5293858859777, 3516.8065756383135, 3517.472379143149, 3523.3481805865995, 3531.258435728526, 630.4893036640336, 630.5020223649767, 641.6054126712038, 651.3226244705548, 651.6090345792582, 530.9123994540857, 550.6448340820228, 556.5186114011432, 649.5258461323166, 655.5968079631087, 8685.869711954325, 8845.867311990325, 9040.502772347583, 9184.311176661777, 9334.859977100348, 523.8611767881514, 599.1611743559052, 652.3215855510311, 653.3140202787055, 678.4632806692845, 3495.598006229265, 4331.610155086058, 4471.262241730143, 4525.343825145335, 4562.794674239667, 87577.12422875817, 89722.55138724265, 109859.45070274598, 118238.0, 121142.0, 72063.63968180126, 86858.0, 87158.56420717856, 91574.08425915788, 94233.28833558294, 61361.0, 76973.61513192399, 77840.61079694565, 82351.17648823555, 84136.57931710302, 9325.953370233105, 9794.951025244829, 9807.9019209807, 9808.558614862492, 9841.901580984384, 33935.0, 48399.0, 51727.74136129295, 56068.158977614556, 56930.715346423, 75560.62219688867, 77572.83640745506, 80279.59860200662, 81369.33837977187, 83317.58341208255, 11113.944430277797, 13073.803892941803, 13350.198988060747, 13788.379522921694, 14432.783508247594, 13817.170969741848, 14157.150570965776, 14413.351399186851, 15094.094354338775, 15419.074855508705, 12754.170978886343, 13423.395947182205, 13769.862301376857, 14310.642233944234, 14426.278686065445, 11371.260868043551, 12877.549285774952, 13477.258750768802, 14500.782488262474, 14669.339879705652, 12591.496340146292, 12789.104762666524, 13537.0, 13552.0, 13742.0, 10860.402677852479, 11211.943940280573, 11441.370724610226, 11729.47217375203, 12126.393680315772, 8504.702335418231, 10354.0, 11396.373199474108, 11565.479553419947, 11600.709982250512], "value": 7165.282930194217, "unit": "score"}], "name": "dromaeo_css", "value": 6983.052941387066, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 133.0, 115.0, 116.0, 110.0, 92.0, 130.0, 170.0, 173.0], "value": 126.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 143.0, 145.0, 162.0, 128.0, 140.0, 137.0, 109.0, 140.0, 119.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 185.0, 184.0, 188.0, 184.0, 185.0, 185.0, 194.0], "value": 185.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 62.0, 114.0, 87.0, 81.0, 59.0, 58.0, 83.0, 57.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 73.0, 95.0, 70.0, 68.0, 68.0, 89.0, 68.0, 68.0, 93.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 104.0, 135.0, 85.0, 88.0, 84.0, 89.0, 87.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 94.0, 98.0, 96.0, 93.0, 93.0, 93.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 83.0, 81.0, 88.0, 122.0, 128.0, 84.0, 82.0, 83.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 55.0, 57.0, 58.0, 59.0, 58.0, 57.0, 57.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 72.0, 103.0, 102.0, 104.0, 71.0, 69.0, 70.0, 71.0, 71.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 86.0, 83.0, 85.0, 92.0, 100.0, 102.0, 100.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 151.0, 148.0, 142.0, 143.0, 140.0, 144.0, 155.0, 213.0, 160.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 173.0, 219.0, 198.0, 162.0, 220.0, 163.0, 165.0, 163.0], "value": 179.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 69.0, 68.0, 66.0, 70.0, 62.0, 83.0, 69.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1508.2, "alertThreshold": 2.0}]} 15:53:27 INFO - Return code: 0 15:53:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:53:27 INFO - # TBPL SUCCESS # 15:53:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:53:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:53:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:53:27 INFO - 2017-01-09 15:53:27.361 screenresolution[1940:20093] starting screenresolution argv=screenresolution get 15:53:27 INFO - 2017-01-09 15:53:27.364 screenresolution[1940:20093] Display 0: 1600x1200x32@60 15:53:27 INFO - 2017-01-09 15:53:27.370 screenresolution[1941:20096] starting screenresolution argv=screenresolution list 15:53:27 INFO - Available Modes on Display 0 15:53:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:53:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:53:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:53:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:53:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:53:27 INFO - Intel Iris: 15:53:27 INFO - Chipset Model: Intel Iris 15:53:27 INFO - Type: GPU 15:53:27 INFO - Bus: Built-In 15:53:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:53:27 INFO - Vendor: Intel (0x8086) 15:53:27 INFO - Device ID: 0x0a2e 15:53:27 INFO - Revision ID: 0x0009 15:53:27 INFO - Displays: 15:53:27 INFO - Display: 15:53:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:53:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:53:27 INFO - Display Serial Number: 1600x1200 60 15:53:27 INFO - Main Display: Yes 15:53:27 INFO - Mirror: Off 15:53:27 INFO - Online: Yes 15:53:27 INFO - Rotation: Supported 15:53:27 INFO - Return code: 0 15:53:27 INFO - Running post-action listener: _resource_record_post_action 15:53:27 INFO - [mozharness: 2017-01-09 23:53:27.606572Z] Finished run-tests step (success) 15:53:27 INFO - Running post-run listener: _resource_record_post_run 15:53:27 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 757760; Write bytes: 657779200; Read time: 11; Write time: 1825 15:53:27 INFO - TinderboxPrint: CPU usage
53.6% 15:53:27 INFO - TinderboxPrint: I/O read bytes / time
757,760 / 11 15:53:27 INFO - TinderboxPrint: I/O write bytes / time
657,779,200 / 1,825 15:53:27 INFO - TinderboxPrint: CPU idle
2,254.3 (46.4%) 15:53:27 INFO - TinderboxPrint: CPU system
973.4 (20.1%) 15:53:27 INFO - TinderboxPrint: CPU user
1,626.0 (33.5%) 15:53:27 INFO - TinderboxPrint: Swap in / out
349,978,624 / 0 15:53:27 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342809088; Write bytes: 336774656; Read time: 27178; Write time: 616 15:53:27 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 757760; Write bytes: 305525760; Read time: 11; Write time: 1159 15:53:28 INFO - Running post-run listener: _upload_blobber_files 15:53:28 INFO - Blob upload gear active. 15:53:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:53:28 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 15:53:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:53:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:53:28 INFO - (blobuploader) - INFO - Open directory for files ... 15:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:30 INFO - (blobuploader) - INFO - Done attempting. 15:53:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:53:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:53:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:31 INFO - (blobuploader) - INFO - Done attempting. 15:53:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:53:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:53:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:53:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:53:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:53:33 INFO - (blobuploader) - INFO - Done attempting. 15:53:33 INFO - (blobuploader) - INFO - Iteration through files over. 15:53:33 INFO - Return code: 0 15:53:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:53:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:53:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdbf4bb7a9f57793cce2512d5e89cc16cba06a846438788f8b30ab1b52cb6bc136531e73112970c03f7cb9ef16c7163c724acfbafe73d4a84e90c00583bf9ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2753b9aad4847a6fb1e63c8b4ef5352661557114c14fda4ed8c1977515ffffc1ea6fc00e3b9dedd975af9ebdb2629e54929eafbbebd7744667cf5e8d1325e39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac122ace17ffd894fd79f8314ae63e75d2bc32ae4dd902a1d3b85a725c0a32fda5dedcbdc19dd3971625b7747771d94068c46f0ecf48e9b0d2808b428a554a03"} 15:53:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:53:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:53:33 INFO - Contents: 15:53:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdbf4bb7a9f57793cce2512d5e89cc16cba06a846438788f8b30ab1b52cb6bc136531e73112970c03f7cb9ef16c7163c724acfbafe73d4a84e90c00583bf9ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2753b9aad4847a6fb1e63c8b4ef5352661557114c14fda4ed8c1977515ffffc1ea6fc00e3b9dedd975af9ebdb2629e54929eafbbebd7744667cf5e8d1325e39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac122ace17ffd894fd79f8314ae63e75d2bc32ae4dd902a1d3b85a725c0a32fda5dedcbdc19dd3971625b7747771d94068c46f0ecf48e9b0d2808b428a554a03"} 15:53:33 INFO - Running post-run listener: copy_logs_to_upload_dir 15:53:33 INFO - Copying logs to upload dir... 15:53:33 INFO - mkdir: /builds/slave/test/build/upload/logs 15:53:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.736742 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-09 15:53:33.363147) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 15:53:33.366258) ========= 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.6UbHzVuCma/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.qkVMq3kmSW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdbf4bb7a9f57793cce2512d5e89cc16cba06a846438788f8b30ab1b52cb6bc136531e73112970c03f7cb9ef16c7163c724acfbafe73d4a84e90c00583bf9ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2753b9aad4847a6fb1e63c8b4ef5352661557114c14fda4ed8c1977515ffffc1ea6fc00e3b9dedd975af9ebdb2629e54929eafbbebd7744667cf5e8d1325e39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac122ace17ffd894fd79f8314ae63e75d2bc32ae4dd902a1d3b85a725c0a32fda5dedcbdc19dd3971625b7747771d94068c46f0ecf48e9b0d2808b428a554a03"} build_url:https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010906 build_url: 'https://queue.taskcluster.net/v1/task/QAwepHeHTy2amkcWVAz89Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdbf4bb7a9f57793cce2512d5e89cc16cba06a846438788f8b30ab1b52cb6bc136531e73112970c03f7cb9ef16c7163c724acfbafe73d4a84e90c00583bf9ccd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2753b9aad4847a6fb1e63c8b4ef5352661557114c14fda4ed8c1977515ffffc1ea6fc00e3b9dedd975af9ebdb2629e54929eafbbebd7744667cf5e8d1325e39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac122ace17ffd894fd79f8314ae63e75d2bc32ae4dd902a1d3b85a725c0a32fda5dedcbdc19dd3971625b7747771d94068c46f0ecf48e9b0d2808b428a554a03"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 15:53:33.405262) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:53:33.405971) ========= 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.6UbHzVuCma/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.qkVMq3kmSW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005109 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 15:53:33.459457) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 15:53:33.460175) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 15:53:33.460906) ========= ========= Total master_lag: 0.22 =========