builder: cedar_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1477922526.08 results: success (0) buildid: 20161031044522 builduid: bb0e356e07ee4e228acc5c2075aeb9a9 revision: 851c5a22724ebb310723ff74e7c3595e676262b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.082448) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.083130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.083461) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.204292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.204599) ========= 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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-31 07:02:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-10-31 07:02:06 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.462634 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.703015) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.703344) ========= 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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.019591 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:06.751899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 07:02:06.752238) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 851c5a22724ebb310723ff74e7c3595e676262b5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 851c5a22724ebb310723ff74e7c3595e676262b5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jKgvmo9EDY/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.4hBQDpS8hu/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-31 07:02:06,831 truncating revision to first 12 chars 2016-10-31 07:02:06,831 Setting DEBUG logging. 2016-10-31 07:02:06,831 attempt 1/10 2016-10-31 07:02:06,831 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/851c5a22724e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 07:02:07,652 unpacking tar archive at: cedar-851c5a22724e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.061062 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 07:02:07.840193) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:07.840532) ========= script_repo_revision: 851c5a22724ebb310723ff74e7c3595e676262b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:07.841001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:07.841274) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 07:02:07.868050) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-31 07:02:07.868467) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jKgvmo9EDY/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.4hBQDpS8hu/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:08 INFO - MultiFileLogger online at 20161031 07:02:08 in /builds/slave/test 07:02:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 07:02:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:02:08 INFO - {'append_to_log': False, 07:02:08 INFO - 'base_work_dir': '/builds/slave/test', 07:02:08 INFO - 'blob_upload_branch': 'Cedar', 07:02:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:02:08 INFO - 'branch': 'Cedar', 07:02:08 INFO - 'buildbot_json_path': 'buildprops.json', 07:02:08 INFO - 'config_files': ('talos/mac_config.py',), 07:02:08 INFO - 'default_actions': ('clobber', 07:02:08 INFO - 'read-buildbot-config', 07:02:08 INFO - 'download-and-extract', 07:02:08 INFO - 'populate-webroot', 07:02:08 INFO - 'create-virtualenv', 07:02:08 INFO - 'install', 07:02:08 INFO - 'run-tests'), 07:02:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:02:08 INFO - 'download_minidump_stackwalk': True, 07:02:08 INFO - 'download_symbols': 'ondemand', 07:02:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:02:08 INFO - 'tooltool.py': '/tools/tooltool.py', 07:02:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:02:08 INFO - '/tools/misc-python/virtualenv.py')}, 07:02:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:02:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:02:08 INFO - 'installer_path': 'installer.exe', 07:02:08 INFO - 'log_level': 'info', 07:02:08 INFO - 'log_name': 'talos', 07:02:08 INFO - 'log_to_console': True, 07:02:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:02:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:02:08 INFO - 'opt_config_files': (), 07:02:08 INFO - 'pip_index': False, 07:02:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:08 INFO - 'cmd': ('bash', 07:02:08 INFO - '-c', 07:02:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:08 INFO - 'enabled': True, 07:02:08 INFO - 'halt_on_failure': False, 07:02:08 INFO - 'name': 'check_screen_resolution'},), 07:02:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:08 INFO - 'cmd': ('bash', 07:02:08 INFO - '-c', 07:02:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:08 INFO - 'enabled': True, 07:02:08 INFO - 'halt_on_failure': False, 07:02:08 INFO - 'name': 'check_screen_resolution'},), 07:02:08 INFO - 'run_cmd_checks_enabled': True, 07:02:08 INFO - 'sps_profile': False, 07:02:08 INFO - 'sps_profile_interval': 0, 07:02:08 INFO - 'suite': 'dromaeojs', 07:02:08 INFO - 'system_bits': '32', 07:02:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:02:08 INFO - 'title': 't-yosemite-r7-0105', 07:02:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:02:08 INFO - 'use_talos_json': True, 07:02:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:02:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:02:08 INFO - 'work_dir': 'build'} 07:02:08 INFO - [mozharness: 2016-10-31 14:02:08.071106Z] Running clobber step. 07:02:08 INFO - Running pre-action listener: _resource_record_pre_action 07:02:08 INFO - Running main action method: clobber 07:02:08 INFO - rmtree: /builds/slave/test/build 07:02:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:02:09 INFO - Running post-action listener: _resource_record_post_action 07:02:09 INFO - [mozharness: 2016-10-31 14:02:09.707459Z] Finished clobber step (success) 07:02:09 INFO - [mozharness: 2016-10-31 14:02:09.707659Z] Running read-buildbot-config step. 07:02:09 INFO - Running pre-action listener: _resource_record_pre_action 07:02:09 INFO - Running main action method: read_buildbot_config 07:02:09 INFO - Using buildbot properties: 07:02:09 INFO - { 07:02:09 INFO - "project": "", 07:02:09 INFO - "product": "firefox", 07:02:09 INFO - "script_repo_revision": "default", 07:02:09 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs", 07:02:09 INFO - "repository": "", 07:02:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs", 07:02:09 INFO - "buildid": "20161031044522", 07:02:09 INFO - "pgo_build": "False", 07:02:09 INFO - "basedir": "/builds/slave/test", 07:02:09 INFO - "buildnumber": 1, 07:02:09 INFO - "slavename": "t-yosemite-r7-0105", 07:02:09 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:02:09 INFO - "platform": "yosemite_r7", 07:02:09 INFO - "scheduler": "tests-cedar-macosx64-talos", 07:02:09 INFO - "branch": "cedar", 07:02:09 INFO - "repo_path": "projects/cedar", 07:02:09 INFO - "revision": "851c5a22724ebb310723ff74e7c3595e676262b5", 07:02:09 INFO - "stage_platform": "macosx64", 07:02:09 INFO - "builduid": "bb0e356e07ee4e228acc5c2075aeb9a9", 07:02:09 INFO - "slavebuilddir": "test" 07:02:09 INFO - } 07:02:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:02:09 INFO - Running post-action listener: _resource_record_post_action 07:02:09 INFO - [mozharness: 2016-10-31 14:02:09.713184Z] Finished read-buildbot-config step (success) 07:02:09 INFO - [mozharness: 2016-10-31 14:02:09.713389Z] Running download-and-extract step. 07:02:09 INFO - Running pre-action listener: _resource_record_pre_action 07:02:09 INFO - Running main action method: download_and_extract 07:02:09 INFO - mkdir: /builds/slave/test/build/tests 07:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:09 INFO - trying https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:02:09 INFO - Downloading https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:02:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 07:02:10 INFO - Downloaded 1199 bytes. 07:02:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:02:10 INFO - Using the following test package requirements: 07:02:10 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:02:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:02:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:02:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'jsshell-mac.zip'], 07:02:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:02:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:02:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:02:10 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:02:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:02:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:02:10 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:02:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:02:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:02:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:02:10 INFO - Fetch https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:02:11 INFO - Http code: 200 07:02:11 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 07:02:11 INFO - x-amz-cf-id: s0jSrR9b2wjz4-O1N7ofVQAJXReqc3pr2q_44LyyfOYHDnslcgUoeA== 07:02:11 INFO - x-amz-version-id: NDgeiN1CYlrAcqSRqvFCd9waUuC1GBP6 07:02:11 INFO - Expected file size: 16392016 07:02:11 INFO - Obtained file size: 16392016 07:02:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:02:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:02:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:02:18 INFO - Fetch https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:02:18 INFO - Http code: 200 07:02:18 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 07:02:18 INFO - x-amz-cf-id: 6dbbErqUNNjloPZrnGiZuGF0uT4P9r_uXMfJz2SyZ7U3vbCdOQL9Gw== 07:02:18 INFO - x-amz-version-id: NDgeiN1CYlrAcqSRqvFCd9waUuC1GBP6 07:02:19 INFO - Expected file size: 16392016 07:02:19 INFO - Obtained file size: 16392016 07:02:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:02:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:02:25 INFO - Fetch https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:02:26 INFO - Http code: 200 07:02:26 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 07:02:26 INFO - x-amz-cf-id: qKWanyM8Pl1dWjZzMqRJElsSeuvefG4PSMgK2F043dOi5o-RIRSShQ== 07:02:26 INFO - x-amz-version-id: FZwt.r11cz7I3_1uIddCl.8BbXjdyMFp 07:02:27 INFO - Expected file size: 14409038 07:02:27 INFO - Obtained file size: 14409038 07:02:27 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')]} 07:02:27 INFO - trying https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:02:27 INFO - Downloading https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:02:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 07:02:30 INFO - Downloaded 112099904 bytes. 07:02:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:02:30 INFO - mkdir: /builds/slave/test/properties 07:02:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:02:30 INFO - Writing to file /builds/slave/test/properties/build_url 07:02:30 INFO - Contents: 07:02:30 INFO - build_url:https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:02:31 INFO - Running post-action listener: _resource_record_post_action 07:02:31 INFO - Running post-action listener: set_extra_try_arguments 07:02:31 INFO - [mozharness: 2016-10-31 14:02:31.207747Z] Finished download-and-extract step (success) 07:02:31 INFO - [mozharness: 2016-10-31 14:02:31.207872Z] Running populate-webroot step. 07:02:31 INFO - Running pre-action listener: _resource_record_pre_action 07:02:31 INFO - Running main action method: populate_webroot 07:02:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:02:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:02:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:02:31 INFO - '--tppagecycles', 07:02:31 INFO - '7'], 07:02:31 INFO - 'tests': ['tsvgm']}}, 07:02:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:02:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:02:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:02:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:02:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:02:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:02:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['damp', 'tps']}, 07:02:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'tests': ['damp', 'tps']}, 07:02:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['dromaeo_dom']}, 07:02:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:02:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['basic_compositor_video']}, 07:02:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:02:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['a11yr', 07:02:31 INFO - 'ts_paint', 07:02:31 INFO - 'tpaint', 07:02:31 INFO - 'sessionrestore', 07:02:31 INFO - 'sessionrestore_no_auto_restore', 07:02:31 INFO - 'tabpaint']}, 07:02:31 INFO - 'other-e10s': {'tests': ['a11yr', 07:02:31 INFO - 'ts_paint', 07:02:31 INFO - 'tpaint', 07:02:31 INFO - 'sessionrestore', 07:02:31 INFO - 'sessionrestore_no_auto_restore', 07:02:31 INFO - 'tabpaint']}, 07:02:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:02:31 INFO - 'ts_paint', 07:02:31 INFO - 'tpaint', 07:02:31 INFO - 'sessionrestore', 07:02:31 INFO - 'sessionrestore_no_auto_restore', 07:02:31 INFO - 'tabpaint']}, 07:02:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:02:31 INFO - 'ts_paint', 07:02:31 INFO - 'tpaint', 07:02:31 INFO - 'sessionrestore', 07:02:31 INFO - 'sessionrestore_no_auto_restore', 07:02:31 INFO - 'tabpaint']}, 07:02:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['a11yr', 07:02:31 INFO - 'ts_paint', 07:02:31 INFO - 'tpaint', 07:02:31 INFO - 'sessionrestore', 07:02:31 INFO - 'sessionrestore_no_auto_restore', 07:02:31 INFO - 'tabpaint']}, 07:02:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['a11yr', 07:02:31 INFO - 'ts_paint', 07:02:31 INFO - 'tpaint', 07:02:31 INFO - 'sessionrestore', 07:02:31 INFO - 'sessionrestore_no_auto_restore', 07:02:31 INFO - 'tabpaint']}, 07:02:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['tsvgx', 07:02:31 INFO - 'tsvgr_opacity', 07:02:31 INFO - 'tart', 07:02:31 INFO - 'tscrollx', 07:02:31 INFO - 'cart']}, 07:02:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:02:31 INFO - 'tsvgr_opacity', 07:02:31 INFO - 'tart', 07:02:31 INFO - 'tscrollx', 07:02:31 INFO - 'cart']}, 07:02:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'talos_options': ['--disable-e10s'], 07:02:31 INFO - 'tests': ['tp5o']}, 07:02:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'tests': ['tp5o']}, 07:02:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'talos_options': ['--disable-e10s', 07:02:31 INFO - '--xperf_path', 07:02:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:02:31 INFO - 'tests': ['tp5n']}, 07:02:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:31 INFO - 'talos_options': ['--xperf_path', 07:02:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:02:31 INFO - 'tests': ['tp5n']}}, 07:02:31 INFO - 'talos.zip': {'path': '', 07:02:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:02:31 INFO - Running post-action listener: _resource_record_post_action 07:02:31 INFO - [mozharness: 2016-10-31 14:02:31.226703Z] Finished populate-webroot step (success) 07:02:31 INFO - [mozharness: 2016-10-31 14:02:31.226831Z] Running create-virtualenv step. 07:02:31 INFO - Running pre-action listener: _resource_record_pre_action 07:02:31 INFO - Running main action method: create_virtualenv 07:02:31 INFO - Creating virtualenv /builds/slave/test/build/venv 07:02:31 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 07:02:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:02:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:02:31 INFO - Using real prefix '/tools/python27' 07:02:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:02:32 INFO - Installing distribute.............................................................................................................................................................................................done. 07:02:34 INFO - Installing pip.................done. 07:02:34 INFO - Return code: 0 07:02:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:02:35 INFO - Reading from file tmpfile_stdout 07:02:35 INFO - Output received: 07:02:35 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) 07:02:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:02:35 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')]} 07:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:35 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')]} 07:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:35 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:35 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 07:02:35 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 07:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:35 INFO - 'HOME': '/Users/cltbld', 07:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:35 INFO - 'LOGNAME': 'cltbld', 07:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:35 INFO - 'MOZ_NO_REMOTE': '1', 07:02:35 INFO - 'NO_EM_RESTART': '1', 07:02:35 INFO - 'PAGER': '/bin/cat', 07:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:35 INFO - 'PWD': '/builds/slave/test', 07:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:35 INFO - 'SHELL': '/bin/bash', 07:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:35 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:35 INFO - 'USER': 'cltbld', 07:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:35 INFO - 'XPC_FLAGS': '0x0', 07:02:35 INFO - 'XPC_SERVICE_NAME': '0', 07:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:35 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 07:02:35 INFO - Cleaning up... 07:02:35 INFO - Return code: 0 07:02:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:02:35 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')]} 07:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:35 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')]} 07:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:35 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:35 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 07:02:35 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 07:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:35 INFO - 'HOME': '/Users/cltbld', 07:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:35 INFO - 'LOGNAME': 'cltbld', 07:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:35 INFO - 'MOZ_NO_REMOTE': '1', 07:02:35 INFO - 'NO_EM_RESTART': '1', 07:02:35 INFO - 'PAGER': '/bin/cat', 07:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:35 INFO - 'PWD': '/builds/slave/test', 07:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:35 INFO - 'SHELL': '/bin/bash', 07:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:35 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:35 INFO - 'USER': 'cltbld', 07:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:35 INFO - 'XPC_FLAGS': '0x0', 07:02:35 INFO - 'XPC_SERVICE_NAME': '0', 07:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:35 INFO - Downloading/unpacking psutil>=3.1.1 07:02:35 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 07:02:35 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 07:02:35 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 07:02:35 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 07:02:35 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 07:02:35 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 07:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:02:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:02:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:02:39 INFO - Installing collected packages: psutil 07:02:39 INFO - Running setup.py install for psutil 07:02:39 INFO - building 'psutil._psutil_osx' extension 07:02:39 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 07:02:40 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 07:02:40 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 07:02:40 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 07:02:40 INFO - building 'psutil._psutil_posix' extension 07:02:40 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 07:02:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:02:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:02:40 INFO - ^ 07:02:40 INFO - 1 warning generated. 07:02:40 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 07:02:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:02:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:02:40 INFO - Successfully installed psutil 07:02:40 INFO - Cleaning up... 07:02:40 INFO - Return code: 0 07:02:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:02:40 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')]} 07:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:40 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')]} 07:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:40 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:40 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 07:02:40 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 07:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:40 INFO - 'HOME': '/Users/cltbld', 07:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:40 INFO - 'LOGNAME': 'cltbld', 07:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:40 INFO - 'MOZ_NO_REMOTE': '1', 07:02:40 INFO - 'NO_EM_RESTART': '1', 07:02:40 INFO - 'PAGER': '/bin/cat', 07:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:40 INFO - 'PWD': '/builds/slave/test', 07:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:40 INFO - 'SHELL': '/bin/bash', 07:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:40 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:40 INFO - 'USER': 'cltbld', 07:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:40 INFO - 'XPC_FLAGS': '0x0', 07:02:40 INFO - 'XPC_SERVICE_NAME': '0', 07:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:02:40 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 07:02:40 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 07:02:40 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 07:02:40 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 07:02:40 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 07:02:40 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 07:02:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:02:43 INFO - Installing collected packages: mozsystemmonitor 07:02:43 INFO - Running setup.py install for mozsystemmonitor 07:02:43 INFO - Successfully installed mozsystemmonitor 07:02:43 INFO - Cleaning up... 07:02:43 INFO - Return code: 0 07:02:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:02:43 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')]} 07:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:43 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')]} 07:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:43 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:43 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 07:02:43 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 07:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:43 INFO - 'HOME': '/Users/cltbld', 07:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:43 INFO - 'LOGNAME': 'cltbld', 07:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:43 INFO - 'MOZ_NO_REMOTE': '1', 07:02:43 INFO - 'NO_EM_RESTART': '1', 07:02:43 INFO - 'PAGER': '/bin/cat', 07:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:43 INFO - 'PWD': '/builds/slave/test', 07:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:43 INFO - 'SHELL': '/bin/bash', 07:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:43 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:43 INFO - 'USER': 'cltbld', 07:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:43 INFO - 'XPC_FLAGS': '0x0', 07:02:43 INFO - 'XPC_SERVICE_NAME': '0', 07:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:43 INFO - Downloading/unpacking jsonschema==2.5.1 07:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:02:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:46 INFO - Downloading functools32-3.2.3-2.tar.gz 07:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:02:46 INFO - warning: no files found matching '*.txt' 07:02:46 INFO - no previously-included directories found matching 'build' 07:02:46 INFO - no previously-included directories found matching 'dist' 07:02:46 INFO - no previously-included directories found matching '.git*' 07:02:46 INFO - Installing collected packages: jsonschema, functools32 07:02:46 INFO - Running setup.py install for functools32 07:02:47 INFO - warning: no files found matching '*.txt' 07:02:47 INFO - no previously-included directories found matching 'build' 07:02:47 INFO - no previously-included directories found matching 'dist' 07:02:47 INFO - no previously-included directories found matching '.git*' 07:02:47 INFO - Successfully installed jsonschema functools32 07:02:47 INFO - Cleaning up... 07:02:47 INFO - Return code: 0 07:02:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:02:47 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')]} 07:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:47 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')]} 07:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:47 INFO - 'HOME': '/Users/cltbld', 07:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:47 INFO - 'LOGNAME': 'cltbld', 07:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:47 INFO - 'MOZ_NO_REMOTE': '1', 07:02:47 INFO - 'NO_EM_RESTART': '1', 07:02:47 INFO - 'PAGER': '/bin/cat', 07:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:47 INFO - 'PWD': '/builds/slave/test', 07:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:47 INFO - 'SHELL': '/bin/bash', 07:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:47 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:47 INFO - 'USER': 'cltbld', 07:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:47 INFO - 'XPC_FLAGS': '0x0', 07:02:47 INFO - 'XPC_SERVICE_NAME': '0', 07:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:02:47 INFO - Cleaning up... 07:02:47 INFO - Return code: 0 07:02:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:02:47 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')]} 07:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:47 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')]} 07:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:47 INFO - 'HOME': '/Users/cltbld', 07:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:47 INFO - 'LOGNAME': 'cltbld', 07:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:47 INFO - 'MOZ_NO_REMOTE': '1', 07:02:47 INFO - 'NO_EM_RESTART': '1', 07:02:47 INFO - 'PAGER': '/bin/cat', 07:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:47 INFO - 'PWD': '/builds/slave/test', 07:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:47 INFO - 'SHELL': '/bin/bash', 07:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:47 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:47 INFO - 'USER': 'cltbld', 07:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:47 INFO - 'XPC_FLAGS': '0x0', 07:02:47 INFO - 'XPC_SERVICE_NAME': '0', 07:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:47 INFO - Downloading/unpacking blobuploader==1.2.4 07:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:50 INFO - Downloading blobuploader-1.2.4.tar.gz 07:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:02:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:02:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:51 INFO - Downloading docopt-0.6.1.tar.gz 07:02:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:02:51 INFO - Installing collected packages: blobuploader, requests, docopt 07:02:51 INFO - Running setup.py install for blobuploader 07:02:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:02:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:02:51 INFO - Running setup.py install for requests 07:02:52 INFO - Running setup.py install for docopt 07:02:52 INFO - Successfully installed blobuploader requests docopt 07:02:52 INFO - Cleaning up... 07:02:52 INFO - Return code: 0 07:02:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02: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')]} 07:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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')]} 07:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:52 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:52 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 07:02:52 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 07:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:52 INFO - 'HOME': '/Users/cltbld', 07:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:52 INFO - 'LOGNAME': 'cltbld', 07:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:52 INFO - 'MOZ_NO_REMOTE': '1', 07:02:52 INFO - 'NO_EM_RESTART': '1', 07:02:52 INFO - 'PAGER': '/bin/cat', 07:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:52 INFO - 'PWD': '/builds/slave/test', 07:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:52 INFO - 'SHELL': '/bin/bash', 07:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:52 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:52 INFO - 'USER': 'cltbld', 07:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:52 INFO - 'XPC_FLAGS': '0x0', 07:02:52 INFO - 'XPC_SERVICE_NAME': '0', 07:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:02:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-8irRoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:02:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-dKNqrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:02:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-BBDrVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:02:52 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-m96Q3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-kjPbu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2MtzyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZV1Z1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ThJCgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-yREkVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-zx7VAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2LHzVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-OqwdiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:53 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-_7caOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-E0bAzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bDenvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-l2a3Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:54 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-sEZunf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:02:54 INFO - Running setup.py install for manifestparser 07:02:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:02:54 INFO - Running setup.py install for mozcrash 07:02:54 INFO - Running setup.py install for mozdebug 07:02:54 INFO - Running setup.py install for mozdevice 07:02:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Running setup.py install for mozfile 07:02:55 INFO - Running setup.py install for mozhttpd 07:02:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Running setup.py install for mozinfo 07:02:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Running setup.py install for mozInstall 07:02:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Running setup.py install for mozleak 07:02:55 INFO - Running setup.py install for mozlog 07:02:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:02:55 INFO - Running setup.py install for moznetwork 07:02:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:02:56 INFO - Running setup.py install for mozprocess 07:02:56 INFO - Running setup.py install for mozprofile 07:02:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:02:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:02:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:02:56 INFO - Running setup.py install for mozrunner 07:02:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:02:56 INFO - Running setup.py install for mozscreenshot 07:02:56 INFO - Running setup.py install for moztest 07:02:56 INFO - Running setup.py install for mozversion 07:02:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:02:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:02:57 INFO - Cleaning up... 07:02:57 INFO - Return code: 0 07:02:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:57 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:02:57 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 07:02:57 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 07:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:57 INFO - 'HOME': '/Users/cltbld', 07:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:57 INFO - 'LOGNAME': 'cltbld', 07:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:57 INFO - 'MOZ_NO_REMOTE': '1', 07:02:57 INFO - 'NO_EM_RESTART': '1', 07:02:57 INFO - 'PAGER': '/bin/cat', 07:02:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:57 INFO - 'PWD': '/builds/slave/test', 07:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:57 INFO - 'SHELL': '/bin/bash', 07:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:02:57 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:57 INFO - 'USER': 'cltbld', 07:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:57 INFO - 'XPC_FLAGS': '0x0', 07:02:57 INFO - 'XPC_SERVICE_NAME': '0', 07:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-GkiQk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:02:57 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)) 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Yk8Kw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-C0FTuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:02:57 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)) 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-tTtC5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:02:57 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)) 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-LAS_P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:02:57 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)) 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-GrDqpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:57 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)) 07:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:57 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZAf9D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-VwKysG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Sxqmj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-enhbVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-daKgRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2G3n9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-6BmX0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-kTJHQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-wyK5Lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:58 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)) 07:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:58 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-TmlX_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:59 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-DPT3eK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:59 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)) 07:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:02:59 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)) 07:02:59 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)) 07:02:59 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)) 07:02:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:02: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 07:02: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 07:02: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 07:02: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 07:02: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 07:02: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 07:03:01 INFO - Downloading blessings-1.6.tar.gz 07:03:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:03:01 INFO - Installing collected packages: blessings 07:03:01 INFO - Running setup.py install for blessings 07:03:01 INFO - Successfully installed blessings 07:03:01 INFO - Cleaning up... 07:03:02 INFO - Return code: 0 07:03:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:03:02 INFO - Reading from file tmpfile_stdout 07:03:02 INFO - Current package versions: 07:03:02 INFO - blessings == 1.6 07:03:02 INFO - blobuploader == 1.2.4 07:03:02 INFO - docopt == 0.6.1 07:03:02 INFO - functools32 == 3.2.3-2 07:03:02 INFO - jsonschema == 2.5.1 07:03:02 INFO - manifestparser == 1.1 07:03:02 INFO - mozInstall == 1.12 07:03:02 INFO - mozcrash == 0.17 07:03:02 INFO - mozdebug == 0.1 07:03:02 INFO - mozdevice == 0.48 07:03:02 INFO - mozfile == 1.2 07:03:02 INFO - mozhttpd == 0.7 07:03:02 INFO - mozinfo == 0.9 07:03:02 INFO - mozleak == 0.1 07:03:02 INFO - mozlog == 3.4 07:03:02 INFO - moznetwork == 0.27 07:03:02 INFO - mozprocess == 0.23 07:03:02 INFO - mozprofile == 0.28 07:03:02 INFO - mozrunner == 6.12 07:03:02 INFO - mozscreenshot == 0.1 07:03:02 INFO - mozsystemmonitor == 0.3 07:03:02 INFO - moztest == 0.7 07:03:02 INFO - mozversion == 1.4 07:03:02 INFO - psutil == 3.1.1 07:03:02 INFO - requests == 1.2.3 07:03:02 INFO - wsgiref == 0.1.2 07:03:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:02 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')]} 07:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:02 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')]} 07:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:02 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:03:02 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 07:03:02 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 07:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:02 INFO - 'HOME': '/Users/cltbld', 07:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:02 INFO - 'LOGNAME': 'cltbld', 07:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:02 INFO - 'MOZ_NO_REMOTE': '1', 07:03:02 INFO - 'NO_EM_RESTART': '1', 07:03:02 INFO - 'PAGER': '/bin/cat', 07:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:02 INFO - 'PWD': '/builds/slave/test', 07:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:02 INFO - 'SHELL': '/bin/bash', 07:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:03:02 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:02 INFO - 'USER': 'cltbld', 07:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:02 INFO - 'XPC_FLAGS': '0x0', 07:03:02 INFO - 'XPC_SERVICE_NAME': '0', 07:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 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)) 07:03:02 INFO - Cleaning up... 07:03:02 INFO - Return code: 0 07:03:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:03:02 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')]} 07:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:02 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')]} 07:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:02 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 0x1029ad490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102923570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac8d62b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, '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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 07:03:02 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 07:03:02 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 07:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:02 INFO - 'HOME': '/Users/cltbld', 07:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:02 INFO - 'LOGNAME': 'cltbld', 07:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:02 INFO - 'MOZ_NO_REMOTE': '1', 07:03:02 INFO - 'NO_EM_RESTART': '1', 07:03:02 INFO - 'PAGER': '/bin/cat', 07:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:02 INFO - 'PWD': '/builds/slave/test', 07:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:02 INFO - 'SHELL': '/bin/bash', 07:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:03:02 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:02 INFO - 'USER': 'cltbld', 07:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:02 INFO - 'XPC_FLAGS': '0x0', 07:03:02 INFO - 'XPC_SERVICE_NAME': '0', 07:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:03:02 INFO - Cleaning up... 07:03:02 INFO - Return code: 0 07:03:02 INFO - Running post-action listener: _resource_record_post_action 07:03:02 INFO - Running post-action listener: _start_resource_monitoring 07:03:02 INFO - Starting resource monitoring. 07:03:02 INFO - [mozharness: 2016-10-31 14:03:02.890507Z] Finished create-virtualenv step (success) 07:03:02 INFO - [mozharness: 2016-10-31 14:03:02.891532Z] Running install step. 07:03:02 INFO - Running pre-action listener: _resource_record_pre_action 07:03:02 INFO - Running main action method: install 07:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:03:03 INFO - Reading from file tmpfile_stdout 07:03:03 INFO - Detecting whether we're running mozinstall >=1.0... 07:03:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:03:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:03:03 INFO - Reading from file tmpfile_stdout 07:03:03 INFO - Output received: 07:03:03 INFO - Usage: mozinstall [options] installer 07:03:03 INFO - Options: 07:03:03 INFO - -h, --help show this help message and exit 07:03:03 INFO - -d DEST, --destination=DEST 07:03:03 INFO - Directory to install application into. [default: 07:03:03 INFO - "/builds/slave/test"] 07:03:03 INFO - --app=APP Application being installed. [default: firefox] 07:03:03 INFO - mkdir: /builds/slave/test/build/application 07:03:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:03:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:03:37 INFO - Reading from file tmpfile_stdout 07:03:37 INFO - Output received: 07:03:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:03:37 INFO - Running post-action listener: _resource_record_post_action 07:03:37 INFO - [mozharness: 2016-10-31 14:03:37.937414Z] Finished install step (success) 07:03:37 INFO - [mozharness: 2016-10-31 14:03:37.937558Z] Running run-tests step. 07:03:37 INFO - Running pre-action listener: _resource_record_pre_action 07:03:37 INFO - Running main action method: run_tests 07:03:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:03:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:03:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:03:37 INFO - 2016-10-31 07:03:37.952 screenresolution[1953:22654] starting screenresolution argv=screenresolution get 07:03:37 INFO - 2016-10-31 07:03:37.954 screenresolution[1953:22654] Display 0: 1600x1200x32@60 07:03:37 INFO - 2016-10-31 07:03:37.960 screenresolution[1954:22656] starting screenresolution argv=screenresolution list 07:03:37 INFO - Available Modes on Display 0 07:03:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:03:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:03:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:03:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:03:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:03:38 INFO - Intel Iris: 07:03:38 INFO - Chipset Model: Intel Iris 07:03:38 INFO - Type: GPU 07:03:38 INFO - Bus: Built-In 07:03:38 INFO - VRAM (Dynamic, Max): 1536 MB 07:03:38 INFO - Vendor: Intel (0x8086) 07:03:38 INFO - Device ID: 0x0a2e 07:03:38 INFO - Revision ID: 0x0009 07:03:38 INFO - Displays: 07:03:38 INFO - Display: 07:03:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:03:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:03:38 INFO - Display Serial Number: 1600x1200 60 07:03:38 INFO - Main Display: Yes 07:03:38 INFO - Mirror: Off 07:03:38 INFO - Online: Yes 07:03:38 INFO - Rotation: Supported 07:03:38 INFO - Return code: 0 07:03:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:03:38 INFO - Python 2.7.3 07:03:38 INFO - Return code: 0 07:03:38 INFO - grabbing minidump binary from tooltool 07:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c1d30>, '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 0x1025e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027b9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:03:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:03:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:03:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:03:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:03:38 INFO - Return code: 0 07:03:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:03:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:03:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:03:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:03:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:03:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 07:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 07:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jKgvmo9EDY/Render', 07:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:38 INFO - 'HOME': '/Users/cltbld', 07:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:38 INFO - 'LOGNAME': 'cltbld', 07:03:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:03:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:38 INFO - 'MOZ_NO_REMOTE': '1', 07:03:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:03:38 INFO - 'NO_EM_RESTART': '1', 07:03:38 INFO - 'PAGER': '/bin/cat', 07:03:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:38 INFO - 'PWD': '/builds/slave/test', 07:03:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:38 INFO - 'SHELL': '/bin/bash', 07:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4hBQDpS8hu/Listeners', 07:03:38 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 07:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:38 INFO - 'USER': 'cltbld', 07:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:38 INFO - 'XPC_FLAGS': '0x0', 07:03:38 INFO - 'XPC_SERVICE_NAME': '0', 07:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 07:03:38 INFO - mozversion application_buildid: 20161031044522 07:03:38 INFO - mozversion application_changeset: 851c5a22724ebb310723ff74e7c3595e676262b5 07:03:38 INFO - mozversion application_display_name: Nightly 07:03:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:03:38 INFO - mozversion application_name: Firefox 07:03:38 INFO - mozversion application_remotingname: firefox 07:03:38 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 07:03:38 INFO - mozversion application_vendor: Mozilla 07:03:38 INFO - mozversion application_version: 52.0a1 07:03:38 INFO - mozversion platform_buildid: 20161031044522 07:03:38 INFO - mozversion platform_changeset: 851c5a22724ebb310723ff74e7c3595e676262b5 07:03:38 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 07:03:38 INFO - mozversion platform_version: 52.0a1 07:03:38 INFO - using testdate: 1477922618 07:03:38 INFO - actual date: 1477922618 07:03:38 INFO - starting webserver on 'localhost:56228' 07:03:38 INFO - SUITE-START | Running 2 tests 07:03:38 INFO - TEST-START | dromaeo_css 07:03:38 INFO - operating with platform_type : mac_ 07:03:38 INFO - Initialising browser for dromaeo_css test... 07:03:38 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmppTn2Kr/profile http://localhost:56228/getInfo.html) 07:03:40 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 07:03:40 INFO - PROCESS | 1964 | colorDepth:24 07:03:40 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 07:03:40 INFO - PROCESS | 1964 | __metrics 07:03:40 INFO - PROCESS | 1964 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:03:40 INFO - PROCESS | 1964 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:03:41 INFO - TEST-INFO | 1964: exit 0 07:03:41 INFO - Browser initialized. 07:03:41 INFO - Running cycle 1/1 for dromaeo_css test... 07:03:41 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmppTn2Kr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:03:53 INFO - PROCESS | 1966 | RSS: Main: 232943616 07:03:53 INFO - PROCESS | 1966 | 07:04:45 INFO - PROCESS | 1966 | 1477922685140 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:06:57 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56228/tests/dromaeo/cssquery-ext.html) 07:06:59 INFO - PROCESS | 1966 | RSS: Main: 384307200 07:06:59 INFO - PROCESS | 1966 | 07:10:02 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-ext.html (next: http://localhost:56228/tests/dromaeo/cssquery-jquery.html) 07:10:02 INFO - PROCESS | 1966 | RSS: Main: 372084736 07:10:02 INFO - PROCESS | 1966 | 07:13:05 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56228/tests/dromaeo/cssquery-mootools.html) 07:13:05 INFO - PROCESS | 1966 | RSS: Main: 335388672 07:13:05 INFO - PROCESS | 1966 | 07:16:08 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56228/tests/dromaeo/cssquery-prototype.html) 07:16:08 INFO - PROCESS | 1966 | RSS: Main: 339140608 07:16:08 INFO - PROCESS | 1966 | 07:19:12 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56228/tests/dromaeo/cssquery-yui.html) 07:19:12 INFO - PROCESS | 1966 | RSS: Main: 332677120 07:19:12 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-yui.html (next: http://localhost:56228/tests/dromaeo/cssquery-dojo.html) 07:22:16 INFO - PROCESS | 1966 | RSS: Main: 378843136 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | __start_tp_report 07:22:16 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 07:22:16 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 07:22:16 INFO - PROCESS | 1966 | |i|pagename|runs| 07:22:16 INFO - PROCESS | 1966 | |0;dojo.html;3296.3572103439833;3310.9337813243737;3315.568976033116;3326.4012477754027;3370.511275865;5525.917111243334;5565.526930210932;5630.774769009245;5654.0670789319765;5694;5580.972095139528;5593.272874526316;5675.177099320596;5677.460641239076;5695.117256825189;5034.82378116766;5062.7468626568525;5100.795968161269;5157.123289040854;5162.845114646548;5229.137192363255;5247.317848679666;5257.447967963366;5264.473552644724;5318.1225097859;5048.596112311006;5199.506046925536;5217.973910130463;5230;5308.044551980642;5084.44071152173;5155.793768249303;5156.252343410185;5156.303898973628;5168.53483186515;5066.949330506684;5136.768845401929;5140.074786538422;5153.381594208681;5180.171172612364;5619.859503512404;5626.324841019104;5628.268325117709;5644.858878528029;5646.435356464362;6093.570266946954;6103.920614266015;6106.764041036324;6113.7499689139695;6140.4473597376455;6125.021800333839;6164.229471316085;6181.81454556364;6210.968945155245;6228.781992630282;5545.861353466155;5660.60375773696;5705.429457054262;5740.770369185186;5764.5676574257095;5242.5281724644965;6273.9372606273355;6281.120643109968;6293.370662933761;6305.936940630535;5968.074948383008;6006.759729610767;6009.218801555857;6014.548908831855;6024.3072046714315;6043.2748070231855;6109.169719594546;6112.938870611237;6113.786017489456;6166.1983942087245;6131.693415329305;6157.830012297649;6175.69121543921;6182.258129024546;6185.752569897244;6199.721012554446;6203.2556093269095;6216.098665693449;6216.595921265103;6219.284782250009;4777.641676874179;4856.3201151838775;4886.804527818918;4897.657163998557;4939.506049395103;4297.376880352332;4336.826526938887;4403.515613282536;4409.4488188976375;4438.334249862546;5749.453801888814;5751.051076572402;5800.897829412398;5804.1293805929445;5814.651120932757;6167.290761562388;6215.658138802319;6238.500919926395;6239.875202495925;6241.188645476059;5464.9180262296095;5615.8876822464135;5680.403557626472;5743.597948143673;5745.770169193269;5282.049231138355;5302.310699609026;5335.893282134336;5344.225087362389;5359.035373632783;6257.7184026718;6266.561340706106;6272.6236425814595;6274.090256912722;6278.183836101278;6198.969005155126;6215.06773983906;6232.096346029891;6251.156093927443;6269.529785266032;6195.349488303663;6262.373762623702;6272.874542509033;6280.528960328085;6285.654289014148;5045.772940217626;6063.636181829104;6132.6627035513475;6150.862090513269;6168.013117901114;6221.657808820377;6235.719392627252;6244.250689917239;6252.093446450148;6252.468540174136;5963.671998040149;6016.608920420159;6065.787697430569;6076.1493390924425;6078.282565239696;4320.697551171388;5817.185594016925;5868.853278668067;5872.29532456108;5953.10703394477;5802.82591522255;5849.415058494116;5853.707314634336;5901.380355062656;5933.465988061095;5955.583109182316;5997.5501837363245;6009.699515024318;6010.489108425658;6016.09758536223;5718.857028574319;5778.364379918122;5860.882782344244;5861.296644402699;5870.970645146747 07:22:16 INFO - PROCESS | 1966 | |1;ext.html;435.3491530162408;511.84834123222737;512.9484556658848;521.1427202252294;563.0568797264581;30578.847105764467;39919.401208981886;40931.79534102333;41029.179416411695;41154.38268425976;42036.78981605095;42076.579234207646;42100;42190.57809421905;42350.78824605873;9328.3003774717;9488.810223795519;9500.192483638899;9505.619775209017;9512.57193426294;9404.764880877965;9513.238940884747;9513.571889265;9514.476703781289;9530.523473826315;9487.71536853892;9558.522073896313;9558.904410955905;9568.23454123672;9570.47362395068;9418.434893906353;9465.00617435173;9480.288978326653;9488.383255088396;9491.715248542554;9260.073992600754;9311.813763724687;9354.672586459441;9356.578953947088;9359.625614975394;39128.41307380392;39460.81617551478;39489.605103949165;39522.01194970149;39577.60422395797;3400.693937545608;3417.2823707021557;3469.1847415857255;3473.4095203815414;3858.3247931612136;29480.557791633142;29609.70390296091;30227.69772302271;30318.69681303181;30416;3173.587433633613;3217.260030193045;3219.7263232625255;3229.4348489014565;3230.4831227003565;475636;480612;481814.5909270466;481952;492432;55936.72031639897;55977.44022559722;56327.43672563304;56382;56423.43576564264;34020.82989585086;59698.70150649219;59789.40210597925;59841.102383464284;59895;15476.22618869052;15897.125658088917;16008.359665613478;16025.59936001586;16038.957467764558;11528.884711152781;12283;12297.877021229673;12361.814572781415;12400.883920447202;18451.90774046148;18456.723149152775;18528.81471185271;18552.979586122623;18574.81425185758;6499.805005849832;7691.384689224848;7827.843443131105;7932.920670793218;7940.325072368798;855.2388374346837;931.4737173496952;957.8132264208413;988.4365911430559;1064.920130990179;25710.61434078453;29011.564826527618;29775.70224297729;30513.23716907095;30758.38483230323;1012.4886932099242;1042.5569133118395;1062.9574817007388;1076.262760009396;1093.7429704019548;15527.534173974798;15536.611584710246;15585.922070389575;15609.843901561066;15887.68224635524;634762;639517;640809;641054;644347;56855;57484;57484.71257643768;57515.42484575184;57660;1214.6477521518661;1270.4664041102758;1336.3518693433678;1368.9253935660458;1392.074270610033;29613;30947.0715878524;30947.69052309448;30981.8450907744;31389.372212556078;47398.52601474079;47881.52118478771;47943;47961.760191198824;47996.28005579989;510796.25843028526;512804;512889;512936;513892;22518.549629007;23033.88483057641;24976.625350619393;25143.245702628952;25795;45713.085738285714;46063.76968115138;46096.07807843891;46655.766721166176;47534;36412.4538131923;37963.810180948916;38219.64870880896;38414;39361.60638393694;31739;32111.518327225578;36199.63800361963;37442.25115497729;38976.610233898435;36783.26433471262;37218.88343349704;37613.24773504569;38044.80977595094;38154.61845381511;26731.866340668174;32772.59252223452;34302;34433;34541.13647158841;24611.384715382257;24979.625305620793;25294.96460424761;25728.871355643103;25773 07:22:16 INFO - PROCESS | 1966 | |2;jquery.html;351.06266269061604;369.7541135145129;370.81273956651904;379.61279494915186;400.7575416872792;12874.935625321872;14739.631509212275;15117.244137793108;20274.69587956182;20756;19600.803991960078;19872.503187420323;20541.07565159568;20722.89638551809;20773.065212065456;5454.400015998227;5761.625494342875;5763.0202865512965;5764.193012978187;5765.365809760923;4202.348635961431;6431.163948686688;6487.805365839009;6549.77075802347;6555.213374395079;6112;6274.309825919168;6453.354664533533;6465.3857883501;6468.708908099147;6235.407636274546;6263.655498947556;6286.874262514724;6301.369863013708;6304.590201636879;5314.202869569557;5542.86142846428;5567.8608034799045;5605.635633683797;5613.915791263134;24425.511489770106;24734.25797226086;24756.381090472703;24982.875085624455;25335.746642533522;2099.454141923111;2375.762423757628;2842.6873043965147;3051.0999255219763;3156.889508867179;11317.49071291794;14851.257437128208;15198.240087995666;15411.691766164726;15437.459688910834;1742.9651406971916;1787.642471505691;1841.9907900460546;1851.0652120679106;1931.488155638743;114541.42729286384;119619;120132;120239.19641205389;122729;8551.615177317035;10175.796484070277;10243.641472548425;10371.896281037092;10688;12972.545960891324;12983.221006739437;12993.285369304589;12996.610101696964;13029.674258143432;8226.218509241613;8321.708740194064;8358;8358.79103022417;8541.487510749375;6915.930840691629;8414.789630259293;8451.873221901677;8496.53269070207;8700.825983480296;5176.7411629418375;6320;6327.240731112296;6337.96831015842;6340.461060809791;1776.4581802550347;1803.4589623113013;1809.4209852847225;1809.819018098206;1812.664657038452;844.8437039147777;892.3575025981283;957.8036502516967;974.3812678948776;1045.4197920154236;19962.001899904946;21405.46486337854;22103;22112;22154.119964404523;831.2975535672347;870.9303255739526;917.9902107681497;968.3124981263242;1010.2667362716554;9467.289953253534;10150.441725705157;10974;11458.94270528642;11504.827427588592;588099;588421;598464;605439;605663;84531.69913765584;86209.1379086201;111933.44033279944;125959;128785;777.249953794591;965.0831709875787;1003.5383723487132;1003.6386900715737;1004.6985904228702;18704.438866833472;19472.415827525198;19489.51276218106;19497.317593884145;19572;53271.20093198527;54480.727596361765;54788.90422191454;55233.72383138059;55432.89134217374;70607.52873649397;77350.22649773656;77964.13996787083;78283;80799;7597.3162415382885;7700.922990770021;7702.768916932501;7733.188015258542;7811.648475818488;12620.43208055658;12660.366981651065;13051.347432628521;13146.80279795823;13152.145110567782;11221.438928053727;11412.885871141514;11605;11616.709582260512;11634.76730465403;10902.836457453303;10933.289336193122;10944.890551094388;11206.887931120584;11302.773944521228;11257.15571332137;11472.426378681199;11568.652940411801;11672;11714.941425292818;7305.525140865827;7641.579713115832;7653.502522336253;7700.268474494914;7925.564093974886;10559.841602375816;10805.189610779065;11140.997310242117;11190.160737944849;11412.657620271406 07:22:16 INFO - PROCESS | 1966 | |3;mootools.html;1543.637245247367;1574.4095964013493;1792.2024699008946;1911.9044047797606;1947.5325921778776;8181.795455113618;8628.266597339218;8640.265577425911;8644.956775216124;8653.134686531344;7558.319751222389;7774.494657847236;7774.8445031099345;8211.302039326665;8424.91575084249;2313.3175713164574;2344.5662552427857;2347.0377145370403;2348.988255058722;2350.6826578411865;2003.9098240579135;2332.5334933013382;2347.2606129069372;2379.4765151666693;2381.214199314231;2319.5013072189477;2319.663648770927;2340.8595484270913;2348.859068455898;2348.976510234893;2309.503456756797;2328.6157783965627;2333.5099629077745;2335.626299792042;2339;2321.651752237161;2351.8588884666806;2354.6114891042957;2357.481354102112;2361.374235827507;8325.375596830261;8527.616257268437;8535.658573657047;8536.530490823003;8538.402311838174;5132.101882170643;5314.441983591706;5318.149096144637;5319.308489896289;5325.440828712968;3299.191698033997;3507.2108775525558;3587.856485740593;3595.083253770298;3595.5146055282726;846.8814365988767;1025.1491262252387;1062.7980683670078;1070.336391437306;1073.5544748929185;11107.666769996913;11108.333499990027;11145.052670523015;11152.442377881154;11166.330020198815;4394.2749446340995;4724.574788269004;4745.976270118627;4748.216544270157;4767.356406885096;9566.18687411564;9571.994940531284;9583.089606487372;9583.99368066343;9585.281103917234;5280.155175171954;5294.073537130987;5294.338207724035;5295.682259064469;5302.92045619316;4327.870163895088;4491.281394976788;4536.3422303765765;4729.456112547101;4781.354517140211;3584.2652256287397;3740.4763333133383;3865.207632435344;3871.9419208711893;3898.6491215790716;1184.4433116435262;1224.3327386574338;1227.5642147037852;1233.6915771057236;1244.0980289290192;809.2554849538438;897.8294124116397;911.252772726358;912.7946212102224;917.2661870503704;5573.637713548606;5692.943070569324;5815.563832712563;5883.872762866327;5992.520598352121;936.784539555906;941.091846368258;951.4814426137772;974.907383798538;996.0836030851634;3215.903522894317;3821.216650586623;3898.200868821885;3939.665128464055;4026.7583944963394;9823.852642210373;10108.494575271206;10226.386416815016;10239.232057595711;10252.794944101077;10199;10200.285979981478;10203;10207.693769186937;10216.744581385525;7517.624118794039;7859;7894.723684671009;7905.841883162419;7971.681132754741;5863.765449381977;5865.501432378295;5997.4002599741425;6030.336662966983;6094.055421409511;9296;9348.485833279236;9475.526223688927;9510.191633710936;9559.808803823746;9313.953430232805;9510.762230944281;9603.663871764455;9762.023797620464;9774.413535187912;7186;7191.9212118182695;7273.527220730635;7357.3746231568775;7369.594672293076;7369.226231245856;7606.163322034461;7616.7334143304715;7686.385089192963;7687.846243074995;6671.432928200963;7150.7497237596435;7243.818904527429;7307.634618269171;7327.230640782641;7093.964530177316;7096.680649370894;7189.532680375759;7270.01854749602;7283.635818209174;6749.190097188369;6796.660166991532;6800.591964482147;6854.931450685429;6856.41720453767;4836.95163048365;4870.732109733999;4922.458529561817;4980.402351717817;5125.692458452505;4240.745555266694;4903.190973489477;4919.877003074952;4981.651284410202;5161.922571161511 07:22:16 INFO - PROCESS | 1966 | |4;prototype.html;294.4523186872417;302.3876649784188;313.04209120092514;315.9289159939014;316.99366012679764;4926.433460152083;4977.859520406224;5095.617828662856;5233;5255.763490642926;5022.623303252252;5072.644914855961;5099.246406940257;5127.743612819363;5145.81989630363;3982.9004274893214;4005.7997100145094;4020.8592699255532;4051.9392209116886;4083.3670781028986;3744.8127593620347;3940.3498422760354;4030.2745505808944;4063.106116654346;4079.653229475498;3976.065624578222;3980.0646847990697;3997.380406037069;4000.0999775050673;4040.393940908858;3463.307338532278;3530.576330840303;3707.8679759138467;3960.1683646433994;4027.577104404025;3226.7412811032373;3235.867446393743;3236.077717850425;3479.3563190809527;3495.4407294832704;4009.83960641574;4350.608445239912;4450.332450132473;4624.398828152319;4733.313669517901;3842.442845787345;4541.591256786905;4571.977140114278;4590.977045114786;4641.767911604406;4770.332153498513;5000.774965126578;5377.3009508764;5429.945700543023;5456.226726313964;3686.4470329450796;3777.924441511182;3841.9039524012123;3861.9227615447817;3897.8501342104146;5052.343195384613;5198.558122559587;5276.1030624793875;5661.547076233891;5838.241028666289;3652.9406472122787;3887.7667339959694;3909.980450097731;3912.7065470089856;3922.3528117860806;3519.085037890167;3605.4952306677087;3837.1558257183374;3976.1451287973223;4034.7377420467574;4456.64346852251;4496.718435245939;4536.274196128604;4558.834553587086;4614.7000444971645;4895.3320440360085;5182.346445755733;5466.781328746805;5510.504054635102;5539.6179642753195;1102.3441052573705;1260.9684757881125;1265.791144461172;1302.5310888080282;1321.282332967139;813.9703275356678;911.589784596934;928.7863791328033;933.8645896345128;943.4669411782321;4497.752623605667;5115.360579927509;5165.66423182492;5186.299849520266;5212.263561630099;5302.011745595401;5505.559555235652;5558.777648893999;5587.217789509472;5674.404187560329;4042.6765858731233;4453.75504347264;4760.714357138583;5043.218301163325;5067.594592432597;2197.637389830692;2221;2376.952460950771;2404.855708657486;2424.9515009699708;3891.7664940103687;4313.654907607384;4483.730976141442;4587.701799383096;4777.378940737682;4152.837205582381;4477.74623105531;4629.953700463019;4641.419822522184;4673.415823022122;4268.487781466182;4519.84180553679;4702.811887524529;4735.928961065587;4753.933712755184;3893.4743809585343;3951.031997160599;4228.217779710763;4446.421075776107;4566.132434837503;5088.59291256706;5698.914516282176;5750.942490575041;5771.336296325809;5789.189513468202;5914.704264786829;6015.939840601538;6021.283934078767;6025.246844144482;6073.696315184135;3283.852226649759;3430.828458577011;3612.0089630965726;3666.2850744104644;3764.0589852536864;4750.952490475189;5621.550275977994;5684.7441865117;5702;5719.5138413235345;4623.93064104032;5504.146857237135;5513.8345849624575;5525.281713377396;5529.668219906818;4838.854834354975;4873.293372461044;5517.200005999188;5540.861478463071;5555.72221388937;4681.829542614347;4706.694064885772;5215.852512447255;5342.79432340608;5359.705216212989;5477.616566840442;5495.368032676293;5497.972510137423;5499.1476321170285;5503.110866794965;4222.472190976128;5294.841154765363;5369.382521009978;5399.406065332889;5456.854060647309 07:22:16 INFO - PROCESS | 1966 | |5;yui.html;589.8643312038232;621.5617989317532;752.033636776742;753.6683859101995;814.885915971764;14157.858421415784;14467.638309042279;14945.701085978273;14988.950773445864;15028.924855375708;13442.126261792977;13720.862791372108;13722.656933576665;13755.381007854645;13761.793573096411;701.2671758012889;871.1288711288711;893.0043002052697;896.7802888292377;901.1214066285385;593.5251798561155;708.8582283543286;860.3418384935529;883.5758835758808;897.6723495923974;937.3953799799125;938.0431959401407;938.9718308450757;939.9389039712431;940.802431489388;927.5130556457846;928.8142371525722;930.5812384426968;931.0875342164649;933.4072863731545;543.2584522127307;560.3583896438596;670.6244503078243;688.7554918004137;698.6157613312657;14560.271986400638;14582.343794529272;14589.197594132318;14599.92700036493;14603.415863365453;13168.209907405491;13285.86714132856;13407;13483;13495;12022.819657705142;12104.81842772359;12185.147039707224;12210.633680989584;12244.632661020183;4262.477851440395;4387.298032314814;4458.799354029076;4487.977560112178;4575.15359658461;64287;91316.08683913143;92460;92851.53574232153;93228.53385733096;7889.723859664884;10513.9474302628;10542.22689179742;10620.575176992834;11041.061509771598;20494.795052049587;20497.59004819925;20516.38450846477;20531.28140515075;20593.79406205949;7517.210692877278;7662.961685191538;7692.653830577638;7703.922960770321;7809.726659567002;4242.830286788494;4340.82636694535;4351.08627188294;4473.575010374009;4479.619232365286;3313.519539666783;3408.9659103409144;3424.931501369959;3425.8972230833115;3427.3830710471857;593.9227900373011;611.0742225528328;611.535233222754;625.1497962770628;626.2297374229632;507.9669821461593;511.4399732293133;524.1351769580223;562.3785716782896;593.2199158107076;7602.923970760222;7758.456908016385;7777.611119444005;7907.802304942423;7976.322012628992;496.84349429929455;524.4651640558607;528.0785030122461;538.6875612144946;540.1492649077718;3588.4796704477703;3770.6417890300377;3854.5374555053013;4116.547179810218;4284.335927931175;84085.80755790377;91566;111118;115691.42154289175;117898;101657;105332.47333763282;106648.40027399595;107576.86347048005;110057;66491;67216.59792525934;85473.57263213643;88116;91991.62012569817;7616.3906887447765;8092.624253876737;8467.36494762883;8692.91307086921;8722.971355621292;41797.582024180585;50744;54094.459055408945;54248.45751542434;55152.44115561706;64197.67901160464;66013;71418.28581714116;73492.89760653701;77829;12635.431405586954;12715.93642031784;12749.553765618159;12894.48422063107;12960.740785184054;14265.358058887583;14570.41718331255;14764.335604897591;14801.037932534351;14957.177355245565;13479.595612131652;13482.52811151605;13678.384472698952;13682.93158534201;14108.506202282872;12998.740025199253;13220.801687974881;13297.335133243492;13299.579898541166;14179.078359906573;12789.74420511566;12828.102032857983;12897.290649014394;13240.40418181165;13728;9205.493697846683;10716.839247411452;11297.152713546353;11564.132690048113;11589.420528973686;8696.738972849029;10223.591056357662;11209.55161793519;11220.66338009861;11446.255993360404 07:22:16 INFO - PROCESS | 1966 | __end_tp_report 07:22:16 INFO - PROCESS | 1966 | __start_cc_report 07:22:16 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,1464 07:22:16 INFO - PROCESS | 1966 | __end_cc_report 07:22:16 INFO - PROCESS | 1966 | __startTimestamp1477923736636__endTimestamp 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | ------- Summary: start ------- 07:22:16 INFO - PROCESS | 1966 | Number of tests: 6 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | [#0] dojo.html Cycles:165 Average:5668.05 Median:5849.42 stddev:642.25 (11.0%) stddev-sans-first:616.68 07:22:16 INFO - PROCESS | 1966 | Values: 3296.4 3310.9 3315.6 3326.4 3370.5 5525.9 5565.5 5630.8 5654.1 5694.0 5581.0 5593.3 5675.2 5677.5 5695.1 5034.8 5062.7 5100.8 5157.1 5162.8 5229.1 5247.3 5257.4 5264.5 5318.1 5048.6 5199.5 5218.0 5230.0 5308.0 5084.4 5155.8 5156.3 5156.3 5168.5 5066.9 5136.8 5140.1 5153.4 5180.2 5619.9 5626.3 5628.3 5644.9 5646.4 6093.6 6103.9 6106.8 6113.7 6140.4 6125.0 6164.2 6181.8 6211.0 6228.8 5545.9 5660.6 5705.4 5740.8 5764.6 5242.5 6273.9 6281.1 6293.4 6305.9 5968.1 6006.8 6009.2 6014.5 6024.3 6043.3 6109.2 6112.9 6113.8 6166.2 6131.7 6157.8 6175.7 6182.3 6185.8 6199.7 6203.3 6216.1 6216.6 6219.3 4777.6 4856.3 4886.8 4897.7 4939.5 4297.4 4336.8 4403.5 4409.4 4438.3 5749.5 5751.1 5800.9 5804.1 5814.7 6167.3 6215.7 6238.5 6239.9 6241.2 5464.9 5615.9 5680.4 5743.6 5745.8 5282.0 5302.3 5335.9 5344.2 5359.0 6257.7 6266.6 6272.6 6274.1 6278.2 6199.0 6215.1 6232.1 6251.2 6269.5 6195.3 6262.4 6272.9 6280.5 6285.7 5045.8 6063.6 6132.7 6150.9 6168.0 6221.7 6235.7 6244.3 6252.1 6252.5 5963.7 6016.6 6065.8 6076.1 6078.3 4320.7 5817.2 5868.9 5872.3 5953.1 5802.8 5849.4 5853.7 5901.4 5933.5 5955.6 5997.6 6009.7 6010.5 6016.1 5718.9 5778.4 5860.9 5861.3 5871.0 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | [#1] ext.html Cycles:180 Average:67464.97 Median:39505.81 stddev:146753.16 (371.5%) stddev-sans-first:147078.55 07:22:16 INFO - PROCESS | 1966 | Values: 435.3 511.8 512.9 521.1 563.1 30578.8 39919.4 40931.8 41029.2 41154.4 42036.8 42076.6 42100.0 42190.6 42350.8 9328.3 9488.8 9500.2 9505.6 9512.6 9404.8 9513.2 9513.6 9514.5 9530.5 9487.7 9558.5 9558.9 9568.2 9570.5 9418.4 9465.0 9480.3 9488.4 9491.7 9260.1 9311.8 9354.7 9356.6 9359.6 39128.4 39460.8 39489.6 39522.0 39577.6 3400.7 3417.3 3469.2 3473.4 3858.3 29480.6 29609.7 30227.7 30318.7 30416.0 3173.6 3217.3 3219.7 3229.4 3230.5 475636.0 480612.0 481814.6 481952.0 492432.0 55936.7 55977.4 56327.4 56382.0 56423.4 34020.8 59698.7 59789.4 59841.1 59895.0 15476.2 15897.1 16008.4 16025.6 16039.0 11528.9 12283.0 12297.9 12361.8 12400.9 18451.9 18456.7 18528.8 18553.0 18574.8 6499.8 7691.4 7827.8 7932.9 7940.3 855.2 931.5 957.8 988.4 1064.9 25710.6 29011.6 29775.7 30513.2 30758.4 1012.5 1042.6 1063.0 1076.3 1093.7 15527.5 15536.6 15585.9 15609.8 15887.7 634762.0 639517.0 640809.0 641054.0 644347.0 56855.0 57484.0 57484.7 57515.4 57660.0 1214.6 1270.5 1336.4 1368.9 1392.1 29613.0 30947.1 30947.7 30981.8 31389.4 47398.5 47881.5 47943.0 47961.8 47996.3 510796.3 512804.0 512889.0 512936.0 513892.0 22518.5 23033.9 24976.6 25143.2 25795.0 45713.1 46063.8 46096.1 46655.8 47534.0 36412.5 37963.8 38219.6 38414.0 39361.6 31739.0 32111.5 36199.6 37442.3 38976.6 36783.3 37218.9 37613.2 38044.8 38154.6 26731.9 32772.6 34302.0 34433.0 34541.1 24611.4 24979.6 25295.0 25728.9 25773.0 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | [#2] jquery.html Cycles:180 Average:34506.55 Median:24869.63 stddev:99285.20 (399.2%) stddev-sans-first:99530.60 07:22:16 INFO - PROCESS | 1966 | Values: 351.1 369.8 370.8 379.6 400.8 12874.9 14739.6 15117.2 20274.7 20756.0 19600.8 19872.5 20541.1 20722.9 20773.1 5454.4 5761.6 5763.0 5764.2 5765.4 4202.3 6431.2 6487.8 6549.8 6555.2 6112.0 6274.3 6453.4 6465.4 6468.7 6235.4 6263.7 6286.9 6301.4 6304.6 5314.2 5542.9 5567.9 5605.6 5613.9 24425.5 24734.3 24756.4 24982.9 25335.7 2099.5 2375.8 2842.7 3051.1 3156.9 11317.5 14851.3 15198.2 15411.7 15437.5 1743.0 1787.6 1842.0 1851.1 1931.5 114541.4 119619.0 120132.0 120239.2 122729.0 8551.6 10175.8 10243.6 10371.9 10688.0 12972.5 12983.2 12993.3 12996.6 13029.7 8226.2 8321.7 8358.0 8358.8 8541.5 6915.9 8414.8 8451.9 8496.5 8700.8 5176.7 6320.0 6327.2 6338.0 6340.5 1776.5 1803.5 1809.4 1809.8 1812.7 844.8 892.4 957.8 974.4 1045.4 19962.0 21405.5 22103.0 22112.0 22154.1 831.3 870.9 918.0 968.3 1010.3 9467.3 10150.4 10974.0 11458.9 11504.8 588099.0 588421.0 598464.0 605439.0 605663.0 84531.7 86209.1 111933.4 125959.0 128785.0 777.2 965.1 1003.5 1003.6 1004.7 18704.4 19472.4 19489.5 19497.3 19572.0 53271.2 54480.7 54788.9 55233.7 55432.9 70607.5 77350.2 77964.1 78283.0 80799.0 7597.3 7700.9 7702.8 7733.2 7811.6 12620.4 12660.4 13051.3 13146.8 13152.1 11221.4 11412.9 11605.0 11616.7 11634.8 10902.8 10933.3 10944.9 11206.9 11302.8 11257.2 11472.4 11568.7 11672.0 11714.9 7305.5 7641.6 7653.5 7700.3 7925.6 10559.8 10805.2 11141.0 11190.2 11412.7 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | [#3] mootools.html Cycles:180 Average:5523.89 Median:5294.21 stddev:3019.65 (57.0%) stddev-sans-first:3013.30 07:22:16 INFO - PROCESS | 1966 | Values: 1543.6 1574.4 1792.2 1911.9 1947.5 8181.8 8628.3 8640.3 8645.0 8653.1 7558.3 7774.5 7774.8 8211.3 8424.9 2313.3 2344.6 2347.0 2349.0 2350.7 2003.9 2332.5 2347.3 2379.5 2381.2 2319.5 2319.7 2340.9 2348.9 2349.0 2309.5 2328.6 2333.5 2335.6 2339.0 2321.7 2351.9 2354.6 2357.5 2361.4 8325.4 8527.6 8535.7 8536.5 8538.4 5132.1 5314.4 5318.1 5319.3 5325.4 3299.2 3507.2 3587.9 3595.1 3595.5 846.9 1025.1 1062.8 1070.3 1073.6 11107.7 11108.3 11145.1 11152.4 11166.3 4394.3 4724.6 4746.0 4748.2 4767.4 9566.2 9572.0 9583.1 9584.0 9585.3 5280.2 5294.1 5294.3 5295.7 5302.9 4327.9 4491.3 4536.3 4729.5 4781.4 3584.3 3740.5 3865.2 3871.9 3898.6 1184.4 1224.3 1227.6 1233.7 1244.1 809.3 897.8 911.3 912.8 917.3 5573.6 5692.9 5815.6 5883.9 5992.5 936.8 941.1 951.5 974.9 996.1 3215.9 3821.2 3898.2 3939.7 4026.8 9823.9 10108.5 10226.4 10239.2 10252.8 10199.0 10200.3 10203.0 10207.7 10216.7 7517.6 7859.0 7894.7 7905.8 7971.7 5863.8 5865.5 5997.4 6030.3 6094.1 9296.0 9348.5 9475.5 9510.2 9559.8 9314.0 9510.8 9603.7 9762.0 9774.4 7186.0 7191.9 7273.5 7357.4 7369.6 7369.2 7606.2 7616.7 7686.4 7687.8 6671.4 7150.7 7243.8 7307.6 7327.2 7094.0 7096.7 7189.5 7270.0 7283.6 6749.2 6796.7 6800.6 6854.9 6856.4 4837.0 4870.7 4922.5 4980.4 5125.7 4240.7 4903.2 4919.9 4981.7 5161.9 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | [#4] prototype.html Cycles:180 Average:4300.39 Median:4627.18 stddev:1324.93 (28.6%) stddev-sans-first:1294.08 07:22:16 INFO - PROCESS | 1966 | Values: 294.5 302.4 313.0 315.9 317.0 4926.4 4977.9 5095.6 5233.0 5255.8 5022.6 5072.6 5099.2 5127.7 5145.8 3982.9 4005.8 4020.9 4051.9 4083.4 3744.8 3940.3 4030.3 4063.1 4079.7 3976.1 3980.1 3997.4 4000.1 4040.4 3463.3 3530.6 3707.9 3960.2 4027.6 3226.7 3235.9 3236.1 3479.4 3495.4 4009.8 4350.6 4450.3 4624.4 4733.3 3842.4 4541.6 4572.0 4591.0 4641.8 4770.3 5000.8 5377.3 5429.9 5456.2 3686.4 3777.9 3841.9 3861.9 3897.9 5052.3 5198.6 5276.1 5661.5 5838.2 3652.9 3887.8 3910.0 3912.7 3922.4 3519.1 3605.5 3837.2 3976.1 4034.7 4456.6 4496.7 4536.3 4558.8 4614.7 4895.3 5182.3 5466.8 5510.5 5539.6 1102.3 1261.0 1265.8 1302.5 1321.3 814.0 911.6 928.8 933.9 943.5 4497.8 5115.4 5165.7 5186.3 5212.3 5302.0 5505.6 5558.8 5587.2 5674.4 4042.7 4453.8 4760.7 5043.2 5067.6 2197.6 2221.0 2377.0 2404.9 2425.0 3891.8 4313.7 4483.7 4587.7 4777.4 4152.8 4477.7 4630.0 4641.4 4673.4 4268.5 4519.8 4702.8 4735.9 4753.9 3893.5 3951.0 4228.2 4446.4 4566.1 5088.6 5698.9 5750.9 5771.3 5789.2 5914.7 6015.9 6021.3 6025.2 6073.7 3283.9 3430.8 3612.0 3666.3 3764.1 4751.0 5621.6 5684.7 5702.0 5719.5 4623.9 5504.1 5513.8 5525.3 5529.7 4838.9 4873.3 5517.2 5540.9 5555.7 4681.8 4706.7 5215.9 5342.8 5359.7 5477.6 5495.4 5498.0 5499.1 5503.1 4222.5 5294.8 5369.4 5399.4 5456.9 07:22:16 INFO - PROCESS | 1966 | 07:22:16 INFO - PROCESS | 1966 | [#5] yui.html Cycles:180 Average:20375.23 Median:4423.05 stddev:29926.49 (676.6%) stddev-sans-first:29973.57 07:22:16 INFO - PROCESS | 1966 | Values: 589.9 621.6 752.0 753.7 814.9 14157.9 14467.6 14945.7 14989.0 15028.9 13442.1 13720.9 13722.7 13755.4 13761.8 701.3 871.1 893.0 896.8 901.1 593.5 708.9 860.3 883.6 897.7 937.4 938.0 939.0 939.9 940.8 927.5 928.8 930.6 931.1 933.4 543.3 560.4 670.6 688.8 698.6 14560.3 14582.3 14589.2 14599.9 14603.4 13168.2 13285.9 13407.0 13483.0 13495.0 12022.8 12104.8 12185.1 12210.6 12244.6 4262.5 4387.3 4458.8 4488.0 4575.2 64287.0 91316.1 92460.0 92851.5 93228.5 7889.7 10513.9 10542.2 10620.6 11041.1 20494.8 20497.6 20516.4 20531.3 20593.8 7517.2 7663.0 7692.7 7703.9 7809.7 4242.8 4340.8 4351.1 4473.6 4479.6 3313.5 3409.0 3424.9 3425.9 3427.4 593.9 611.1 611.5 625.1 626.2 508.0 511.4 524.1 562.4 593.2 7602.9 7758.5 7777.6 7907.8 7976.3 496.8 524.5 528.1 538.7 540.1 3588.5 3770.6 3854.5 4116.5 4284.3 84085.8 91566.0 111118.0 115691.4 117898.0 101657.0 105332.5 106648.4 107576.9 110057.0 66491.0 67216.6 85473.6 88116.0 91991.6 7616.4 8092.6 8467.4 8692.9 8723.0 41797.6 50744.0 54094.5 54248.5 55152.4 64197.7 66013.0 71418.3 73492.9 77829.0 12635.4 12715.9 12749.6 12894.5 12960.7 14265.4 14570.4 14764.3 14801.0 14957.2 13479.6 13482.5 13678.4 13682.9 14108.5 12998.7 13220.8 13297.3 13299.6 14179.1 12789.7 12828.1 12897.3 13240.4 13728.0 9205.5 10716.8 11297.2 11564.1 11589.4 8696.7 10223.6 11209.6 11220.7 11446.3 07:22:16 INFO - PROCESS | 1966 | -------- Summary: end -------- 07:22:16 INFO - PROCESS | 1966 | 07:22:17 INFO - TEST-INFO | 1966: exit 0 07:22:17 INFO - TEST-OK | dromaeo_css | took 1118740ms 07:22:17 INFO - TEST-START | kraken 07:22:17 INFO - operating with platform_type : mac_ 07:22:17 INFO - Initialising browser for kraken test... 07:22:17 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpn9F3yQ/profile http://localhost:56228/getInfo.html) 07:22:19 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 07:22:19 INFO - PROCESS | 1969 | colorDepth:24 07:22:19 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 07:22:19 INFO - PROCESS | 1969 | __metrics 07:22:19 INFO - PROCESS | 1969 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:22:19 INFO - PROCESS | 1969 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:22:20 INFO - TEST-INFO | 1969: exit 0 07:22:20 INFO - Browser initialized. 07:22:20 INFO - Running cycle 1/1 for kraken test... 07:22:20 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpn9F3yQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:32 INFO - PROCESS | 1970 | RSS: Main: 241475584 07:22:32 INFO - PROCESS | 1970 | 07:22:35 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-beat-detection) 07:22:35 INFO - PROCESS | 1970 | RSS: Main: 292462592 07:22:35 INFO - PROCESS | 1970 | 07:22:38 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-dft) 07:22:39 INFO - PROCESS | 1970 | RSS: Main: 293642240 07:22:39 INFO - PROCESS | 1970 | 07:22:42 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-fft) 07:22:43 INFO - PROCESS | 1970 | RSS: Main: 386420736 07:22:43 INFO - PROCESS | 1970 | 07:22:45 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-oscillator) 07:22:45 INFO - PROCESS | 1970 | RSS: Main: 286826496 07:22:45 INFO - PROCESS | 1970 | 07:22:47 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:22:48 INFO - PROCESS | 1970 | RSS: Main: 291016704 07:22:48 INFO - PROCESS | 1970 | 07:22:51 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56228/tests/kraken/driver.html?testName=imaging-darkroom) 07:22:51 INFO - PROCESS | 1970 | RSS: Main: 327495680 07:22:51 INFO - PROCESS | 1970 | 07:22:54 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56228/tests/kraken/driver.html?testName=imaging-desaturate) 07:22:55 INFO - PROCESS | 1970 | RSS: Main: 329814016 07:22:55 INFO - PROCESS | 1970 | 07:22:58 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56228/tests/kraken/driver.html?testName=json-parse-financial) 07:22:58 INFO - PROCESS | 1970 | RSS: Main: 327872512 07:22:58 INFO - PROCESS | 1970 | 07:23:00 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:23:01 INFO - PROCESS | 1970 | RSS: Main: 304893952 07:23:01 INFO - PROCESS | 1970 | 07:23:02 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:23:03 INFO - PROCESS | 1970 | RSS: Main: 308817920 07:23:03 INFO - PROCESS | 1970 | 07:23:05 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:23:06 INFO - PROCESS | 1970 | RSS: Main: 317186048 07:23:06 INFO - PROCESS | 1970 | 07:23:09 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:23:10 INFO - PROCESS | 1970 | RSS: Main: 327614464 07:23:10 INFO - PROCESS | 1970 | 07:23:13 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:23:13 INFO - PROCESS | 1970 | RSS: Main: 321511424 07:23:13 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56228/tests/kraken/driver.html?testName=ai-astar) 07:23:15 INFO - PROCESS | 1970 | RSS: Main: 365314048 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | __start_tp_report 07:23:15 INFO - PROCESS | 1970 | _x_x_mozilla_page_load 07:23:15 INFO - PROCESS | 1970 | _x_x_mozilla_page_load_details 07:23:15 INFO - PROCESS | 1970 | |i|pagename|runs| 07:23:15 INFO - PROCESS | 1970 | |0;ai-astar;110;112;112;113;112;112;112;113;112;112 07:23:15 INFO - PROCESS | 1970 | |1;audio-beat-detection;144;113;97;154;130;132;145;142;145;111 07:23:15 INFO - PROCESS | 1970 | |2;audio-dft;184;184;182;186;186;183;180;181;183;184 07:23:15 INFO - PROCESS | 1970 | |3;audio-fft;80;59;59;93;64;61;59;59;82;58 07:23:15 INFO - PROCESS | 1970 | |4;audio-oscillator;88;70;92;70;68;69;91;67;67;89 07:23:15 INFO - PROCESS | 1970 | |5;imaging-gaussian-blur;88;85;87;86;86;83;90;130;88;84 07:23:15 INFO - PROCESS | 1970 | |6;imaging-darkroom;132;132;93;93;92;94;92;93;93;92 07:23:15 INFO - PROCESS | 1970 | |7;imaging-desaturate;80;82;82;83;83;82;86;84;82;82 07:23:15 INFO - PROCESS | 1970 | |8;json-parse-financial;57;53;55;48;54;55;55;55;54;56 07:23:15 INFO - PROCESS | 1970 | |9;json-stringify-tinderbox;72;72;82;73;73;69;69;70;71;70 07:23:15 INFO - PROCESS | 1970 | |10;stanford-crypto-aes;92;88;90;87;87;85;88;87;92;88 07:23:15 INFO - PROCESS | 1970 | |11;stanford-crypto-ccm;147;144;146;144;149;163;156;149;182;148 07:23:15 INFO - PROCESS | 1970 | |12;stanford-crypto-pbkdf2;171;173;174;173;177;240;181;170;173;175 07:23:15 INFO - PROCESS | 1970 | |13;stanford-crypto-sha256-iterative;67;68;69;66;66;66;66;66;65;68 07:23:15 INFO - PROCESS | 1970 | __end_tp_report 07:23:15 INFO - PROCESS | 1970 | __start_cc_report 07:23:15 INFO - PROCESS | 1970 | _x_x_mozilla_cycle_collect,2015 07:23:15 INFO - PROCESS | 1970 | __end_cc_report 07:23:15 INFO - PROCESS | 1970 | __startTimestamp1477923795690__endTimestamp 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | ------- Summary: start ------- 07:23:15 INFO - PROCESS | 1970 | Number of tests: 14 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:0.82 (0.7%) stddev-sans-first:0.44 07:23:15 INFO - PROCESS | 1970 | Values: 110.0 112.0 112.0 113.0 112.0 112.0 112.0 113.0 112.0 112.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:144.50 stddev:18.54 (12.8%) stddev-sans-first:19.08 07:23:15 INFO - PROCESS | 1970 | Values: 144.0 113.0 97.0 154.0 130.0 132.0 145.0 142.0 145.0 111.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.95 (1.1%) stddev-sans-first:2.05 07:23:15 INFO - PROCESS | 1970 | Values: 184.0 184.0 182.0 186.0 186.0 183.0 180.0 181.0 183.0 184.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#3] audio-fft Cycles:10 Average:67.40 Median:62.50 stddev:12.69 (20.3%) stddev-sans-first:12.62 07:23:15 INFO - PROCESS | 1970 | Values: 80.0 59.0 59.0 93.0 64.0 61.0 59.0 59.0 82.0 58.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:79.00 stddev:11.20 (14.2%) stddev-sans-first:11.16 07:23:15 INFO - PROCESS | 1970 | Values: 88.0 70.0 92.0 70.0 68.0 69.0 91.0 67.0 67.0 89.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:86.50 stddev:13.96 (16.1%) stddev-sans-first:14.77 07:23:15 INFO - PROCESS | 1970 | Values: 88.0 85.0 87.0 86.0 86.0 83.0 90.0 130.0 88.0 84.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:93.00 stddev:16.56 (17.8%) stddev-sans-first:13.10 07:23:15 INFO - PROCESS | 1970 | Values: 132.0 132.0 93.0 93.0 92.0 94.0 92.0 93.0 93.0 92.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#7] imaging-desaturate Cycles:10 Average:82.60 Median:82.50 stddev:1.58 (1.9%) stddev-sans-first:1.36 07:23:15 INFO - PROCESS | 1970 | Values: 80.0 82.0 82.0 83.0 83.0 82.0 86.0 84.0 82.0 82.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:55.00 stddev:2.44 (4.4%) stddev-sans-first:2.37 07:23:15 INFO - PROCESS | 1970 | Values: 57.0 53.0 55.0 48.0 54.0 55.0 55.0 55.0 54.0 56.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.00 stddev:3.78 (5.3%) stddev-sans-first:4.01 07:23:15 INFO - PROCESS | 1970 | Values: 72.0 72.0 82.0 73.0 73.0 69.0 69.0 70.0 71.0 70.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:88.00 stddev:2.27 (2.6%) stddev-sans-first:2.00 07:23:15 INFO - PROCESS | 1970 | Values: 92.0 88.0 90.0 87.0 87.0 85.0 88.0 87.0 92.0 88.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:149.00 stddev:11.80 (7.9%) stddev-sans-first:12.33 07:23:15 INFO - PROCESS | 1970 | Values: 147.0 144.0 146.0 144.0 149.0 163.0 156.0 149.0 182.0 148.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:174.50 stddev:21.07 (12.1%) stddev-sans-first:22.05 07:23:15 INFO - PROCESS | 1970 | Values: 171.0 173.0 174.0 173.0 177.0 240.0 181.0 170.0 173.0 175.0 07:23:15 INFO - PROCESS | 1970 | 07:23:15 INFO - PROCESS | 1970 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.32 07:23:15 INFO - PROCESS | 1970 | Values: 67.0 68.0 69.0 66.0 66.0 66.0 66.0 66.0 65.0 68.0 07:23:15 INFO - PROCESS | 1970 | -------- Summary: end -------- 07:23:15 INFO - PROCESS | 1970 | 07:23:16 INFO - TEST-INFO | 1970: exit 0 07:23:16 INFO - TEST-OK | kraken | took 59299ms 07:23:16 INFO - SUITE-END | took 1178s 07:23:16 INFO - Completed test suite (00:19:38) 07:23:16 INFO - javascript benchmark 07:23:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.3572103439833, 3310.9337813243737, 3315.568976033116, 3326.4012477754027, 3370.511275865, 5525.917111243334, 5565.526930210932, 5630.774769009245, 5654.0670789319765, 5694.0, 5580.972095139528, 5593.272874526316, 5675.177099320596, 5677.460641239076, 5695.117256825189, 5034.82378116766, 5062.7468626568525, 5100.795968161269, 5157.123289040854, 5162.845114646548, 5229.137192363255, 5247.317848679666, 5257.447967963366, 5264.473552644724, 5318.1225097859, 5048.596112311006, 5199.506046925536, 5217.973910130463, 5230.0, 5308.044551980642, 5084.44071152173, 5155.793768249303, 5156.252343410185, 5156.303898973628, 5168.53483186515, 5066.949330506684, 5136.768845401929, 5140.074786538422, 5153.381594208681, 5180.171172612364, 5619.859503512404, 5626.324841019104, 5628.268325117709, 5644.858878528029, 5646.435356464362, 6093.570266946954, 6103.920614266015, 6106.764041036324, 6113.7499689139695, 6140.4473597376455, 6125.021800333839, 6164.229471316085, 6181.81454556364, 6210.968945155245, 6228.781992630282, 5545.861353466155, 5660.60375773696, 5705.429457054262, 5740.770369185186, 5764.5676574257095, 5242.5281724644965, 6273.9372606273355, 6281.120643109968, 6293.370662933761, 6305.936940630535, 5968.074948383008, 6006.759729610767, 6009.218801555857, 6014.548908831855, 6024.3072046714315, 6043.2748070231855, 6109.169719594546, 6112.938870611237, 6113.786017489456, 6166.1983942087245, 6131.693415329305, 6157.830012297649, 6175.69121543921, 6182.258129024546, 6185.752569897244, 6199.721012554446, 6203.2556093269095, 6216.098665693449, 6216.595921265103, 6219.284782250009, 4777.641676874179, 4856.3201151838775, 4886.804527818918, 4897.657163998557, 4939.506049395103, 4297.376880352332, 4336.826526938887, 4403.515613282536, 4409.4488188976375, 4438.334249862546, 5749.453801888814, 5751.051076572402, 5800.897829412398, 5804.1293805929445, 5814.651120932757, 6167.290761562388, 6215.658138802319, 6238.500919926395, 6239.875202495925, 6241.188645476059, 5464.9180262296095, 5615.8876822464135, 5680.403557626472, 5743.597948143673, 5745.770169193269, 5282.049231138355, 5302.310699609026, 5335.893282134336, 5344.225087362389, 5359.035373632783, 6257.7184026718, 6266.561340706106, 6272.6236425814595, 6274.090256912722, 6278.183836101278, 6198.969005155126, 6215.06773983906, 6232.096346029891, 6251.156093927443, 6269.529785266032, 6195.349488303663, 6262.373762623702, 6272.874542509033, 6280.528960328085, 6285.654289014148, 5045.772940217626, 6063.636181829104, 6132.6627035513475, 6150.862090513269, 6168.013117901114, 6221.657808820377, 6235.719392627252, 6244.250689917239, 6252.093446450148, 6252.468540174136, 5963.671998040149, 6016.608920420159, 6065.787697430569, 6076.1493390924425, 6078.282565239696, 4320.697551171388, 5817.185594016925, 5868.853278668067, 5872.29532456108, 5953.10703394477, 5802.82591522255, 5849.415058494116, 5853.707314634336, 5901.380355062656, 5933.465988061095, 5955.583109182316, 5997.5501837363245, 6009.699515024318, 6010.489108425658, 6016.09758536223, 5718.857028574319, 5778.364379918122, 5860.882782344244, 5861.296644402699, 5870.970645146747], "value": 5627.563603605319, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.3491530162408, 511.84834123222737, 512.9484556658848, 521.1427202252294, 563.0568797264581, 30578.847105764467, 39919.401208981886, 40931.79534102333, 41029.179416411695, 41154.38268425976, 42036.78981605095, 42076.579234207646, 42100.0, 42190.57809421905, 42350.78824605873, 9328.3003774717, 9488.810223795519, 9500.192483638899, 9505.619775209017, 9512.57193426294, 9404.764880877965, 9513.238940884747, 9513.571889265, 9514.476703781289, 9530.523473826315, 9487.71536853892, 9558.522073896313, 9558.904410955905, 9568.23454123672, 9570.47362395068, 9418.434893906353, 9465.00617435173, 9480.288978326653, 9488.383255088396, 9491.715248542554, 9260.073992600754, 9311.813763724687, 9354.672586459441, 9356.578953947088, 9359.625614975394, 39128.41307380392, 39460.81617551478, 39489.605103949165, 39522.01194970149, 39577.60422395797, 3400.693937545608, 3417.2823707021557, 3469.1847415857255, 3473.4095203815414, 3858.3247931612136, 29480.557791633142, 29609.70390296091, 30227.69772302271, 30318.69681303181, 30416.0, 3173.587433633613, 3217.260030193045, 3219.7263232625255, 3229.4348489014565, 3230.4831227003565, 475636.0, 480612.0, 481814.5909270466, 481952.0, 492432.0, 55936.72031639897, 55977.44022559722, 56327.43672563304, 56382.0, 56423.43576564264, 34020.82989585086, 59698.70150649219, 59789.40210597925, 59841.102383464284, 59895.0, 15476.22618869052, 15897.125658088917, 16008.359665613478, 16025.59936001586, 16038.957467764558, 11528.884711152781, 12283.0, 12297.877021229673, 12361.814572781415, 12400.883920447202, 18451.90774046148, 18456.723149152775, 18528.81471185271, 18552.979586122623, 18574.81425185758, 6499.805005849832, 7691.384689224848, 7827.843443131105, 7932.920670793218, 7940.325072368798, 855.2388374346837, 931.4737173496952, 957.8132264208413, 988.4365911430559, 1064.920130990179, 25710.61434078453, 29011.564826527618, 29775.70224297729, 30513.23716907095, 30758.38483230323, 1012.4886932099242, 1042.5569133118395, 1062.9574817007388, 1076.262760009396, 1093.7429704019548, 15527.534173974798, 15536.611584710246, 15585.922070389575, 15609.843901561066, 15887.68224635524, 634762.0, 639517.0, 640809.0, 641054.0, 644347.0, 56855.0, 57484.0, 57484.71257643768, 57515.42484575184, 57660.0, 1214.6477521518661, 1270.4664041102758, 1336.3518693433678, 1368.9253935660458, 1392.074270610033, 29613.0, 30947.0715878524, 30947.69052309448, 30981.8450907744, 31389.372212556078, 47398.52601474079, 47881.52118478771, 47943.0, 47961.760191198824, 47996.28005579989, 510796.25843028526, 512804.0, 512889.0, 512936.0, 513892.0, 22518.549629007, 23033.88483057641, 24976.625350619393, 25143.245702628952, 25795.0, 45713.085738285714, 46063.76968115138, 46096.07807843891, 46655.766721166176, 47534.0, 36412.4538131923, 37963.810180948916, 38219.64870880896, 38414.0, 39361.60638393694, 31739.0, 32111.518327225578, 36199.63800361963, 37442.25115497729, 38976.610233898435, 36783.26433471262, 37218.88343349704, 37613.24773504569, 38044.80977595094, 38154.61845381511, 26731.866340668174, 32772.59252223452, 34302.0, 34433.0, 34541.13647158841, 24611.384715382257, 24979.625305620793, 25294.96460424761, 25728.871355643103, 25773.0], "value": 19487.21341864066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.06266269061604, 369.7541135145129, 370.81273956651904, 379.61279494915186, 400.7575416872792, 12874.935625321872, 14739.631509212275, 15117.244137793108, 20274.69587956182, 20756.0, 19600.803991960078, 19872.503187420323, 20541.07565159568, 20722.89638551809, 20773.065212065456, 5454.400015998227, 5761.625494342875, 5763.0202865512965, 5764.193012978187, 5765.365809760923, 4202.348635961431, 6431.163948686688, 6487.805365839009, 6549.77075802347, 6555.213374395079, 6112.0, 6274.309825919168, 6453.354664533533, 6465.3857883501, 6468.708908099147, 6235.407636274546, 6263.655498947556, 6286.874262514724, 6301.369863013708, 6304.590201636879, 5314.202869569557, 5542.86142846428, 5567.8608034799045, 5605.635633683797, 5613.915791263134, 24425.511489770106, 24734.25797226086, 24756.381090472703, 24982.875085624455, 25335.746642533522, 2099.454141923111, 2375.762423757628, 2842.6873043965147, 3051.0999255219763, 3156.889508867179, 11317.49071291794, 14851.257437128208, 15198.240087995666, 15411.691766164726, 15437.459688910834, 1742.9651406971916, 1787.642471505691, 1841.9907900460546, 1851.0652120679106, 1931.488155638743, 114541.42729286384, 119619.0, 120132.0, 120239.19641205389, 122729.0, 8551.615177317035, 10175.796484070277, 10243.641472548425, 10371.896281037092, 10688.0, 12972.545960891324, 12983.221006739437, 12993.285369304589, 12996.610101696964, 13029.674258143432, 8226.218509241613, 8321.708740194064, 8358.0, 8358.79103022417, 8541.487510749375, 6915.930840691629, 8414.789630259293, 8451.873221901677, 8496.53269070207, 8700.825983480296, 5176.7411629418375, 6320.0, 6327.240731112296, 6337.96831015842, 6340.461060809791, 1776.4581802550347, 1803.4589623113013, 1809.4209852847225, 1809.819018098206, 1812.664657038452, 844.8437039147777, 892.3575025981283, 957.8036502516967, 974.3812678948776, 1045.4197920154236, 19962.001899904946, 21405.46486337854, 22103.0, 22112.0, 22154.119964404523, 831.2975535672347, 870.9303255739526, 917.9902107681497, 968.3124981263242, 1010.2667362716554, 9467.289953253534, 10150.441725705157, 10974.0, 11458.94270528642, 11504.827427588592, 588099.0, 588421.0, 598464.0, 605439.0, 605663.0, 84531.69913765584, 86209.1379086201, 111933.44033279944, 125959.0, 128785.0, 777.249953794591, 965.0831709875787, 1003.5383723487132, 1003.6386900715737, 1004.6985904228702, 18704.438866833472, 19472.415827525198, 19489.51276218106, 19497.317593884145, 19572.0, 53271.20093198527, 54480.727596361765, 54788.90422191454, 55233.72383138059, 55432.89134217374, 70607.52873649397, 77350.22649773656, 77964.13996787083, 78283.0, 80799.0, 7597.3162415382885, 7700.922990770021, 7702.768916932501, 7733.188015258542, 7811.648475818488, 12620.43208055658, 12660.366981651065, 13051.347432628521, 13146.80279795823, 13152.145110567782, 11221.438928053727, 11412.885871141514, 11605.0, 11616.709582260512, 11634.76730465403, 10902.836457453303, 10933.289336193122, 10944.890551094388, 11206.887931120584, 11302.773944521228, 11257.15571332137, 11472.426378681199, 11568.652940411801, 11672.0, 11714.941425292818, 7305.525140865827, 7641.579713115832, 7653.502522336253, 7700.268474494914, 7925.564093974886, 10559.841602375816, 10805.189610779065, 11140.997310242117, 11190.160737944849, 11412.657620271406], "value": 9620.386152217856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.637245247367, 1574.4095964013493, 1792.2024699008946, 1911.9044047797606, 1947.5325921778776, 8181.795455113618, 8628.266597339218, 8640.265577425911, 8644.956775216124, 8653.134686531344, 7558.319751222389, 7774.494657847236, 7774.8445031099345, 8211.302039326665, 8424.91575084249, 2313.3175713164574, 2344.5662552427857, 2347.0377145370403, 2348.988255058722, 2350.6826578411865, 2003.9098240579135, 2332.5334933013382, 2347.2606129069372, 2379.4765151666693, 2381.214199314231, 2319.5013072189477, 2319.663648770927, 2340.8595484270913, 2348.859068455898, 2348.976510234893, 2309.503456756797, 2328.6157783965627, 2333.5099629077745, 2335.626299792042, 2339.0, 2321.651752237161, 2351.8588884666806, 2354.6114891042957, 2357.481354102112, 2361.374235827507, 8325.375596830261, 8527.616257268437, 8535.658573657047, 8536.530490823003, 8538.402311838174, 5132.101882170643, 5314.441983591706, 5318.149096144637, 5319.308489896289, 5325.440828712968, 3299.191698033997, 3507.2108775525558, 3587.856485740593, 3595.083253770298, 3595.5146055282726, 846.8814365988767, 1025.1491262252387, 1062.7980683670078, 1070.336391437306, 1073.5544748929185, 11107.666769996913, 11108.333499990027, 11145.052670523015, 11152.442377881154, 11166.330020198815, 4394.2749446340995, 4724.574788269004, 4745.976270118627, 4748.216544270157, 4767.356406885096, 9566.18687411564, 9571.994940531284, 9583.089606487372, 9583.99368066343, 9585.281103917234, 5280.155175171954, 5294.073537130987, 5294.338207724035, 5295.682259064469, 5302.92045619316, 4327.870163895088, 4491.281394976788, 4536.3422303765765, 4729.456112547101, 4781.354517140211, 3584.2652256287397, 3740.4763333133383, 3865.207632435344, 3871.9419208711893, 3898.6491215790716, 1184.4433116435262, 1224.3327386574338, 1227.5642147037852, 1233.6915771057236, 1244.0980289290192, 809.2554849538438, 897.8294124116397, 911.252772726358, 912.7946212102224, 917.2661870503704, 5573.637713548606, 5692.943070569324, 5815.563832712563, 5883.872762866327, 5992.520598352121, 936.784539555906, 941.091846368258, 951.4814426137772, 974.907383798538, 996.0836030851634, 3215.903522894317, 3821.216650586623, 3898.200868821885, 3939.665128464055, 4026.7583944963394, 9823.852642210373, 10108.494575271206, 10226.386416815016, 10239.232057595711, 10252.794944101077, 10199.0, 10200.285979981478, 10203.0, 10207.693769186937, 10216.744581385525, 7517.624118794039, 7859.0, 7894.723684671009, 7905.841883162419, 7971.681132754741, 5863.765449381977, 5865.501432378295, 5997.4002599741425, 6030.336662966983, 6094.055421409511, 9296.0, 9348.485833279236, 9475.526223688927, 9510.191633710936, 9559.808803823746, 9313.953430232805, 9510.762230944281, 9603.663871764455, 9762.023797620464, 9774.413535187912, 7186.0, 7191.9212118182695, 7273.527220730635, 7357.3746231568775, 7369.594672293076, 7369.226231245856, 7606.163322034461, 7616.7334143304715, 7686.385089192963, 7687.846243074995, 6671.432928200963, 7150.7497237596435, 7243.818904527429, 7307.634618269171, 7327.230640782641, 7093.964530177316, 7096.680649370894, 7189.532680375759, 7270.01854749602, 7283.635818209174, 6749.190097188369, 6796.660166991532, 6800.591964482147, 6854.931450685429, 6856.41720453767, 4836.95163048365, 4870.732109733999, 4922.458529561817, 4980.402351717817, 5125.692458452505, 4240.745555266694, 4903.190973489477, 4919.877003074952, 4981.651284410202, 5161.922571161511], "value": 4479.415139060043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.4523186872417, 302.3876649784188, 313.04209120092514, 315.9289159939014, 316.99366012679764, 4926.433460152083, 4977.859520406224, 5095.617828662856, 5233.0, 5255.763490642926, 5022.623303252252, 5072.644914855961, 5099.246406940257, 5127.743612819363, 5145.81989630363, 3982.9004274893214, 4005.7997100145094, 4020.8592699255532, 4051.9392209116886, 4083.3670781028986, 3744.8127593620347, 3940.3498422760354, 4030.2745505808944, 4063.106116654346, 4079.653229475498, 3976.065624578222, 3980.0646847990697, 3997.380406037069, 4000.0999775050673, 4040.393940908858, 3463.307338532278, 3530.576330840303, 3707.8679759138467, 3960.1683646433994, 4027.577104404025, 3226.7412811032373, 3235.867446393743, 3236.077717850425, 3479.3563190809527, 3495.4407294832704, 4009.83960641574, 4350.608445239912, 4450.332450132473, 4624.398828152319, 4733.313669517901, 3842.442845787345, 4541.591256786905, 4571.977140114278, 4590.977045114786, 4641.767911604406, 4770.332153498513, 5000.774965126578, 5377.3009508764, 5429.945700543023, 5456.226726313964, 3686.4470329450796, 3777.924441511182, 3841.9039524012123, 3861.9227615447817, 3897.8501342104146, 5052.343195384613, 5198.558122559587, 5276.1030624793875, 5661.547076233891, 5838.241028666289, 3652.9406472122787, 3887.7667339959694, 3909.980450097731, 3912.7065470089856, 3922.3528117860806, 3519.085037890167, 3605.4952306677087, 3837.1558257183374, 3976.1451287973223, 4034.7377420467574, 4456.64346852251, 4496.718435245939, 4536.274196128604, 4558.834553587086, 4614.7000444971645, 4895.3320440360085, 5182.346445755733, 5466.781328746805, 5510.504054635102, 5539.6179642753195, 1102.3441052573705, 1260.9684757881125, 1265.791144461172, 1302.5310888080282, 1321.282332967139, 813.9703275356678, 911.589784596934, 928.7863791328033, 933.8645896345128, 943.4669411782321, 4497.752623605667, 5115.360579927509, 5165.66423182492, 5186.299849520266, 5212.263561630099, 5302.011745595401, 5505.559555235652, 5558.777648893999, 5587.217789509472, 5674.404187560329, 4042.6765858731233, 4453.75504347264, 4760.714357138583, 5043.218301163325, 5067.594592432597, 2197.637389830692, 2221.0, 2376.952460950771, 2404.855708657486, 2424.9515009699708, 3891.7664940103687, 4313.654907607384, 4483.730976141442, 4587.701799383096, 4777.378940737682, 4152.837205582381, 4477.74623105531, 4629.953700463019, 4641.419822522184, 4673.415823022122, 4268.487781466182, 4519.84180553679, 4702.811887524529, 4735.928961065587, 4753.933712755184, 3893.4743809585343, 3951.031997160599, 4228.217779710763, 4446.421075776107, 4566.132434837503, 5088.59291256706, 5698.914516282176, 5750.942490575041, 5771.336296325809, 5789.189513468202, 5914.704264786829, 6015.939840601538, 6021.283934078767, 6025.246844144482, 6073.696315184135, 3283.852226649759, 3430.828458577011, 3612.0089630965726, 3666.2850744104644, 3764.0589852536864, 4750.952490475189, 5621.550275977994, 5684.7441865117, 5702.0, 5719.5138413235345, 4623.93064104032, 5504.146857237135, 5513.8345849624575, 5525.281713377396, 5529.668219906818, 4838.854834354975, 4873.293372461044, 5517.200005999188, 5540.861478463071, 5555.72221388937, 4681.829542614347, 4706.694064885772, 5215.852512447255, 5342.79432340608, 5359.705216212989, 5477.616566840442, 5495.368032676293, 5497.972510137423, 5499.1476321170285, 5503.110866794965, 4222.472190976128, 5294.841154765363, 5369.382521009978, 5399.406065332889, 5456.854060647309], "value": 3886.5999764751305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.8643312038232, 621.5617989317532, 752.033636776742, 753.6683859101995, 814.885915971764, 14157.858421415784, 14467.638309042279, 14945.701085978273, 14988.950773445864, 15028.924855375708, 13442.126261792977, 13720.862791372108, 13722.656933576665, 13755.381007854645, 13761.793573096411, 701.2671758012889, 871.1288711288711, 893.0043002052697, 896.7802888292377, 901.1214066285385, 593.5251798561155, 708.8582283543286, 860.3418384935529, 883.5758835758808, 897.6723495923974, 937.3953799799125, 938.0431959401407, 938.9718308450757, 939.9389039712431, 940.802431489388, 927.5130556457846, 928.8142371525722, 930.5812384426968, 931.0875342164649, 933.4072863731545, 543.2584522127307, 560.3583896438596, 670.6244503078243, 688.7554918004137, 698.6157613312657, 14560.271986400638, 14582.343794529272, 14589.197594132318, 14599.92700036493, 14603.415863365453, 13168.209907405491, 13285.86714132856, 13407.0, 13483.0, 13495.0, 12022.819657705142, 12104.81842772359, 12185.147039707224, 12210.633680989584, 12244.632661020183, 4262.477851440395, 4387.298032314814, 4458.799354029076, 4487.977560112178, 4575.15359658461, 64287.0, 91316.08683913143, 92460.0, 92851.53574232153, 93228.53385733096, 7889.723859664884, 10513.9474302628, 10542.22689179742, 10620.575176992834, 11041.061509771598, 20494.795052049587, 20497.59004819925, 20516.38450846477, 20531.28140515075, 20593.79406205949, 7517.210692877278, 7662.961685191538, 7692.653830577638, 7703.922960770321, 7809.726659567002, 4242.830286788494, 4340.82636694535, 4351.08627188294, 4473.575010374009, 4479.619232365286, 3313.519539666783, 3408.9659103409144, 3424.931501369959, 3425.8972230833115, 3427.3830710471857, 593.9227900373011, 611.0742225528328, 611.535233222754, 625.1497962770628, 626.2297374229632, 507.9669821461593, 511.4399732293133, 524.1351769580223, 562.3785716782896, 593.2199158107076, 7602.923970760222, 7758.456908016385, 7777.611119444005, 7907.802304942423, 7976.322012628992, 496.84349429929455, 524.4651640558607, 528.0785030122461, 538.6875612144946, 540.1492649077718, 3588.4796704477703, 3770.6417890300377, 3854.5374555053013, 4116.547179810218, 4284.335927931175, 84085.80755790377, 91566.0, 111118.0, 115691.42154289175, 117898.0, 101657.0, 105332.47333763282, 106648.40027399595, 107576.86347048005, 110057.0, 66491.0, 67216.59792525934, 85473.57263213643, 88116.0, 91991.62012569817, 7616.3906887447765, 8092.624253876737, 8467.36494762883, 8692.91307086921, 8722.971355621292, 41797.582024180585, 50744.0, 54094.459055408945, 54248.45751542434, 55152.44115561706, 64197.67901160464, 66013.0, 71418.28581714116, 73492.89760653701, 77829.0, 12635.431405586954, 12715.93642031784, 12749.553765618159, 12894.48422063107, 12960.740785184054, 14265.358058887583, 14570.41718331255, 14764.335604897591, 14801.037932534351, 14957.177355245565, 13479.595612131652, 13482.52811151605, 13678.384472698952, 13682.93158534201, 14108.506202282872, 12998.740025199253, 13220.801687974881, 13297.335133243492, 13299.579898541166, 14179.078359906573, 12789.74420511566, 12828.102032857983, 12897.290649014394, 13240.40418181165, 13728.0, 9205.493697846683, 10716.839247411452, 11297.152713546353, 11564.132690048113, 11589.420528973686, 8696.738972849029, 10223.591056357662, 11209.55161793519, 11220.66338009861, 11446.255993360404], "value": 7229.607904461076, "unit": "score"}], "name": "dromaeo_css", "value": 7142.81689980474, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 97.0, 154.0, 130.0, 132.0, 145.0, 142.0, 145.0, 111.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 182.0, 186.0, 186.0, 183.0, 180.0, 181.0, 183.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 93.0, 64.0, 61.0, 59.0, 59.0, 82.0, 58.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 92.0, 70.0, 68.0, 69.0, 91.0, 67.0, 67.0, 89.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 86.0, 86.0, 83.0, 90.0, 130.0, 88.0, 84.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 93.0, 93.0, 92.0, 94.0, 92.0, 93.0, 93.0, 92.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 82.0, 83.0, 83.0, 82.0, 86.0, 84.0, 82.0, 82.0], "value": 82.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 55.0, 48.0, 54.0, 55.0, 55.0, 55.0, 54.0, 56.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 82.0, 73.0, 73.0, 69.0, 69.0, 70.0, 71.0, 70.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 90.0, 87.0, 87.0, 85.0, 88.0, 87.0, 92.0, 88.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 144.0, 146.0, 144.0, 149.0, 163.0, 156.0, 149.0, 182.0, 148.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 174.0, 173.0, 177.0, 240.0, 181.0, 170.0, 173.0, 175.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 68.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1459.9000000000003, "alertThreshold": 2.0}]} 07:23:16 INFO - Return code: 0 07:23:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:23:17 INFO - # TBPL SUCCESS # 07:23:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:23:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:23:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:23:17 INFO - 2016-10-31 07:23:17.035 screenresolution[1974:24828] starting screenresolution argv=screenresolution get 07:23:17 INFO - 2016-10-31 07:23:17.037 screenresolution[1974:24828] Display 0: 1600x1200x32@60 07:23:17 INFO - 2016-10-31 07:23:17.043 screenresolution[1975:24831] starting screenresolution argv=screenresolution list 07:23:17 INFO - Available Modes on Display 0 07:23:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:23:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:23:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:23:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:23:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:23:17 INFO - Intel Iris: 07:23:17 INFO - Chipset Model: Intel Iris 07:23:17 INFO - Type: GPU 07:23:17 INFO - Bus: Built-In 07:23:17 INFO - VRAM (Dynamic, Max): 1536 MB 07:23:17 INFO - Vendor: Intel (0x8086) 07:23:17 INFO - Device ID: 0x0a2e 07:23:17 INFO - Revision ID: 0x0009 07:23:17 INFO - Displays: 07:23:17 INFO - Display: 07:23:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:23:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:23:17 INFO - Display Serial Number: 1600x1200 60 07:23:17 INFO - Main Display: Yes 07:23:17 INFO - Mirror: Off 07:23:17 INFO - Online: Yes 07:23:17 INFO - Rotation: Supported 07:23:17 INFO - Return code: 0 07:23:17 INFO - Running post-action listener: _resource_record_post_action 07:23:17 INFO - [mozharness: 2016-10-31 14:23:17.279062Z] Finished run-tests step (success) 07:23:17 INFO - Running post-run listener: _resource_record_post_run 07:23:17 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 344064; Write bytes: 708017152; Read time: 5; Write time: 1936 07:23:17 INFO - TinderboxPrint: CPU usage
53.5% 07:23:17 INFO - TinderboxPrint: I/O read bytes / time
344,064 / 5 07:23:17 INFO - TinderboxPrint: I/O write bytes / time
708,017,152 / 1,936 07:23:17 INFO - TinderboxPrint: CPU idle
2,256.0 (46.5%) 07:23:17 INFO - TinderboxPrint: CPU system
972.3 (20.0%) 07:23:17 INFO - TinderboxPrint: CPU user
1,625.4 (33.5%) 07:23:17 INFO - TinderboxPrint: Swap in / out
406,953,984 / 0 07:23:17 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 408031744; Write bytes: 409827328; Read time: 31289; Write time: 768 07:23:17 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 299008; Write bytes: 296690688; Read time: 4; Write time: 1161 07:23:17 INFO - Running post-run listener: _upload_blobber_files 07:23:17 INFO - Blob upload gear active. 07:23:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:23:17 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 07:23:17 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:23:17 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:23:18 INFO - (blobuploader) - INFO - Open directory for files ... 07:23:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:23:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:23:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:19 INFO - (blobuploader) - INFO - Done attempting. 07:23:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:23:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:23:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:20 INFO - (blobuploader) - INFO - Done attempting. 07:23:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:23:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:23:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:22 INFO - (blobuploader) - INFO - Done attempting. 07:23:22 INFO - (blobuploader) - INFO - Iteration through files over. 07:23:22 INFO - Return code: 0 07:23:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:23:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:23:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f53f6c1a2893fe742de78f4799af459620988722f22fa5b0b4cfa713f1bf4d9d03ec280d62b6c748d8a653f06155cbd5a238d4861739ef34e315c10106649c92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6b25b3f25e6c7f45d03ced8b4f12a84f06ac183250204d08f3e45513ea758c10fef6808d043a23f34e172ced52e85aea85a03f1c976a423c3efac411b26c2c91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/127ce1cfe02392df65404ea0b8a271d2f40835f15603c69766b2f7faa9978d5089a3f7ed28d735710f6878b140bce893751f5a67a5b068a7f40c59d038b294d2"} 07:23:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:23:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:23:22 INFO - Contents: 07:23:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f53f6c1a2893fe742de78f4799af459620988722f22fa5b0b4cfa713f1bf4d9d03ec280d62b6c748d8a653f06155cbd5a238d4861739ef34e315c10106649c92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6b25b3f25e6c7f45d03ced8b4f12a84f06ac183250204d08f3e45513ea758c10fef6808d043a23f34e172ced52e85aea85a03f1c976a423c3efac411b26c2c91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/127ce1cfe02392df65404ea0b8a271d2f40835f15603c69766b2f7faa9978d5089a3f7ed28d735710f6878b140bce893751f5a67a5b068a7f40c59d038b294d2"} 07:23:22 INFO - Running post-run listener: copy_logs_to_upload_dir 07:23:22 INFO - Copying logs to upload dir... 07:23:22 INFO - mkdir: /builds/slave/test/build/upload/logs 07:23:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.833659 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-31 07:23:22.740424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 07:23:22.743689) ========= 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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/Cedar/sha512/f53f6c1a2893fe742de78f4799af459620988722f22fa5b0b4cfa713f1bf4d9d03ec280d62b6c748d8a653f06155cbd5a238d4861739ef34e315c10106649c92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6b25b3f25e6c7f45d03ced8b4f12a84f06ac183250204d08f3e45513ea758c10fef6808d043a23f34e172ced52e85aea85a03f1c976a423c3efac411b26c2c91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/127ce1cfe02392df65404ea0b8a271d2f40835f15603c69766b2f7faa9978d5089a3f7ed28d735710f6878b140bce893751f5a67a5b068a7f40c59d038b294d2"} build_url:https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/GDbvZF9WRJGUqog1hfg0Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f53f6c1a2893fe742de78f4799af459620988722f22fa5b0b4cfa713f1bf4d9d03ec280d62b6c748d8a653f06155cbd5a238d4861739ef34e315c10106649c92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6b25b3f25e6c7f45d03ced8b4f12a84f06ac183250204d08f3e45513ea758c10fef6808d043a23f34e172ced52e85aea85a03f1c976a423c3efac411b26c2c91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/127ce1cfe02392df65404ea0b8a271d2f40835f15603c69766b2f7faa9978d5089a3f7ed28d735710f6878b140bce893751f5a67a5b068a7f40c59d038b294d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 07:23:22.777251) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:23:22.777620) ========= 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.jKgvmo9EDY/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.4hBQDpS8hu/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005145 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 07:23:22.826028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 07:23:22.826367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 07:23:22.826668) ========= ========= Total master_lag: 0.20 =========