builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0179 starttime: 1479191524.68 results: success (0) buildid: 20161114200813 builduid: a084c28fbee94eaa924165c2a82e9cc7 revision: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:04.681520) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:04.682034) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:04.682346) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:04.706652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:04.707075) ========= 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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 22:32:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.6M=0s 2016-11-14 22:32:04 (39.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124057 ========= master_lag: 0.76 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:05.593344) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:05.593732) ========= 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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.019477 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:05.634997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:32:05.635386) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZbIBZtYC3x/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.4lbtWtEubk/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 22:32:05,710 truncating revision to first 12 chars 2016-11-14 22:32:05,710 Setting DEBUG logging. 2016-11-14 22:32:05,710 attempt 1/10 2016-11-14 22:32:05,710 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d32b34582655?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 22:32:06,485 unpacking tar archive at: mozilla-aurora-d32b34582655/testing/mozharness/ program finished with exit code 0 elapsedTime=1.619681 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:32:07.376961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:07.377307) ========= script_repo_revision: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:07.377788) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:07.378085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:32:07.396881) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-11-14 22:32:07.397283) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZbIBZtYC3x/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.4lbtWtEubk/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:32:07 INFO - MultiFileLogger online at 20161114 22:32:07 in /builds/slave/test 22:32:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:32:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:32:07 INFO - {'append_to_log': False, 22:32:07 INFO - 'base_work_dir': '/builds/slave/test', 22:32:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:32:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:32:07 INFO - 'branch': 'Mozilla-Aurora', 22:32:07 INFO - 'buildbot_json_path': 'buildprops.json', 22:32:07 INFO - 'config_files': ('talos/mac_config.py',), 22:32:07 INFO - 'default_actions': ('clobber', 22:32:07 INFO - 'read-buildbot-config', 22:32:07 INFO - 'download-and-extract', 22:32:07 INFO - 'populate-webroot', 22:32:07 INFO - 'create-virtualenv', 22:32:07 INFO - 'install', 22:32:07 INFO - 'run-tests'), 22:32:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:32:07 INFO - 'download_minidump_stackwalk': True, 22:32:07 INFO - 'download_symbols': 'ondemand', 22:32:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:32:07 INFO - 'tooltool.py': '/tools/tooltool.py', 22:32:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:32:07 INFO - '/tools/misc-python/virtualenv.py')}, 22:32:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:32:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:32:07 INFO - 'installer_path': 'installer.exe', 22:32:07 INFO - 'log_level': 'info', 22:32:07 INFO - 'log_name': 'talos', 22:32:07 INFO - 'log_to_console': True, 22:32:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:32:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:32:07 INFO - 'opt_config_files': (), 22:32:07 INFO - 'pip_index': False, 22:32:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:32:07 INFO - 'cmd': ('bash', 22:32:07 INFO - '-c', 22:32:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:32:07 INFO - 'enabled': True, 22:32:07 INFO - 'halt_on_failure': False, 22:32:07 INFO - 'name': 'check_screen_resolution'},), 22:32:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:32:07 INFO - 'cmd': ('bash', 22:32:07 INFO - '-c', 22:32:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:32:07 INFO - 'enabled': True, 22:32:07 INFO - 'halt_on_failure': False, 22:32:07 INFO - 'name': 'check_screen_resolution'},), 22:32:07 INFO - 'run_cmd_checks_enabled': True, 22:32:07 INFO - 'sps_profile': False, 22:32:07 INFO - 'sps_profile_interval': 0, 22:32:07 INFO - 'suite': 'dromaeojs', 22:32:07 INFO - 'system_bits': '32', 22:32:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:32:07 INFO - 'title': 't-yosemite-r7-0179', 22:32:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:32:07 INFO - 'use_talos_json': True, 22:32:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:32:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:32:07 INFO - 'work_dir': 'build'} 22:32:07 INFO - [mozharness: 2016-11-15 06:32:07.604402Z] Running clobber step. 22:32:07 INFO - Running pre-action listener: _resource_record_pre_action 22:32:07 INFO - Running main action method: clobber 22:32:07 INFO - rmtree: /builds/slave/test/build 22:32:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:32:09 INFO - Running post-action listener: _resource_record_post_action 22:32:09 INFO - [mozharness: 2016-11-15 06:32:09.135568Z] Finished clobber step (success) 22:32:09 INFO - [mozharness: 2016-11-15 06:32:09.135710Z] Running read-buildbot-config step. 22:32:09 INFO - Running pre-action listener: _resource_record_pre_action 22:32:09 INFO - Running main action method: read_buildbot_config 22:32:09 INFO - Using buildbot properties: 22:32:09 INFO - { 22:32:09 INFO - "project": "", 22:32:09 INFO - "product": "firefox", 22:32:09 INFO - "script_repo_revision": "production", 22:32:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 22:32:09 INFO - "repository": "", 22:32:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 22:32:09 INFO - "buildid": "20161114200813", 22:32:09 INFO - "pgo_build": "False", 22:32:09 INFO - "basedir": "/builds/slave/test", 22:32:09 INFO - "buildnumber": 91, 22:32:09 INFO - "slavename": "t-yosemite-r7-0179", 22:32:09 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:32:09 INFO - "platform": "yosemite_r7", 22:32:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:32:09 INFO - "branch": "mozilla-aurora", 22:32:09 INFO - "repo_path": "releases/mozilla-aurora", 22:32:09 INFO - "revision": "d32b3458265589c2e9bc2f9413b5a3256a6f9ea3", 22:32:09 INFO - "stage_platform": "macosx64", 22:32:09 INFO - "builduid": "a084c28fbee94eaa924165c2a82e9cc7", 22:32:09 INFO - "slavebuilddir": "test" 22:32:09 INFO - } 22:32:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 22:32:09 INFO - Running post-action listener: _resource_record_post_action 22:32:09 INFO - [mozharness: 2016-11-15 06:32:09.139093Z] Finished read-buildbot-config step (success) 22:32:09 INFO - [mozharness: 2016-11-15 06:32:09.139238Z] Running download-and-extract step. 22:32:09 INFO - Running pre-action listener: _resource_record_pre_action 22:32:09 INFO - Running main action method: download_and_extract 22:32:09 INFO - mkdir: /builds/slave/test/build/tests 22:32: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')]} 22:32:09 INFO - trying https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 22:32:09 INFO - Downloading https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:32:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 22:32:10 INFO - Downloaded 1199 bytes. 22:32:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 22:32:10 INFO - Using the following test package requirements: 22:32:10 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:32:10 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 22:32:10 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 22:32:10 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'jsshell-mac.zip'], 22:32:10 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 22:32:10 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 22:32:10 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 22:32:10 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 22:32:10 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 22:32:10 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 22:32:10 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 22:32:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:32:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:32:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:32:10 INFO - Fetch https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:32:11 INFO - Http code: 200 22:32:11 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 22:32:11 INFO - x-amz-cf-id: J-87SqVTljLt2bxojnyKm-mrnZIyCeav8Ho6shOwn0QwXU9IwgKopA== 22:32:11 INFO - x-amz-version-id: WgL7mKdWg65Xy27OSB5kFNEbBKUIOSKb 22:32:11 INFO - Expected file size: 16327482 22:32:11 INFO - Obtained file size: 16327482 22:32:17 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 22:32:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 22:32:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:32:17 INFO - Fetch https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 22:32:18 INFO - Http code: 200 22:32:18 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:32:18 INFO - x-amz-cf-id: RK-zcGAFjzt-_vCYsaYVOWF5vMVj5EKIWbK-GoTaK-g7ilZ7OZbing== 22:32:18 INFO - x-amz-version-id: WgL7mKdWg65Xy27OSB5kFNEbBKUIOSKb 22:32:18 INFO - Expected file size: 16327482 22:32:18 INFO - Obtained file size: 16327482 22:32:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 22:32:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:32:25 INFO - Fetch https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 22:32:25 INFO - Http code: 200 22:32:25 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 22:32:25 INFO - x-amz-cf-id: xgKWfSNmG7xJ77qcApoV8E1QecFotKkWZzVknivmYHQ0mzfiOTSwPA== 22:32:25 INFO - x-amz-version-id: flEjvKsnC5n.IRuRbeKSLjY8wOCk8YgJ 22:32:26 INFO - Expected file size: 14409038 22:32:26 INFO - Obtained file size: 14409038 22:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:26 INFO - trying https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:32:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 22:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 22:32:29 INFO - Downloaded 114363240 bytes. 22:32:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:32:29 INFO - mkdir: /builds/slave/test/properties 22:32:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:32:29 INFO - Writing to file /builds/slave/test/properties/build_url 22:32:29 INFO - Contents: 22:32:29 INFO - build_url:https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 22:32:30 INFO - Running post-action listener: _resource_record_post_action 22:32:30 INFO - Running post-action listener: set_extra_try_arguments 22:32:30 INFO - [mozharness: 2016-11-15 06:32:30.030336Z] Finished download-and-extract step (success) 22:32:30 INFO - [mozharness: 2016-11-15 06:32:30.030469Z] Running populate-webroot step. 22:32:30 INFO - Running pre-action listener: _resource_record_pre_action 22:32:30 INFO - Running main action method: populate_webroot 22:32:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:32:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:32:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:32:30 INFO - '--tppagecycles', 22:32:30 INFO - '7'], 22:32:30 INFO - 'tests': ['tsvgm']}}, 22:32:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:32:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:32:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:32:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:32:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:32:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:32:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['damp', 'tps']}, 22:32:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'tests': ['damp', 'tps']}, 22:32:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['dromaeo_dom']}, 22:32:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:32:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['basic_compositor_video']}, 22:32:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:32:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['a11yr', 22:32:30 INFO - 'ts_paint', 22:32:30 INFO - 'tpaint', 22:32:30 INFO - 'sessionrestore', 22:32:30 INFO - 'sessionrestore_no_auto_restore', 22:32:30 INFO - 'tabpaint']}, 22:32:30 INFO - 'other-e10s': {'tests': ['a11yr', 22:32:30 INFO - 'ts_paint', 22:32:30 INFO - 'tpaint', 22:32:30 INFO - 'sessionrestore', 22:32:30 INFO - 'sessionrestore_no_auto_restore', 22:32:30 INFO - 'tabpaint']}, 22:32:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:32:30 INFO - 'ts_paint', 22:32:30 INFO - 'tpaint', 22:32:30 INFO - 'sessionrestore', 22:32:30 INFO - 'sessionrestore_no_auto_restore', 22:32:30 INFO - 'tabpaint']}, 22:32:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:32:30 INFO - 'ts_paint', 22:32:30 INFO - 'tpaint', 22:32:30 INFO - 'sessionrestore', 22:32:30 INFO - 'sessionrestore_no_auto_restore', 22:32:30 INFO - 'tabpaint']}, 22:32:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['a11yr', 22:32:30 INFO - 'ts_paint', 22:32:30 INFO - 'tpaint', 22:32:30 INFO - 'sessionrestore', 22:32:30 INFO - 'sessionrestore_no_auto_restore', 22:32:30 INFO - 'tabpaint']}, 22:32:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['a11yr', 22:32:30 INFO - 'ts_paint', 22:32:30 INFO - 'tpaint', 22:32:30 INFO - 'sessionrestore', 22:32:30 INFO - 'sessionrestore_no_auto_restore', 22:32:30 INFO - 'tabpaint']}, 22:32:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['tsvgx', 22:32:30 INFO - 'tsvgr_opacity', 22:32:30 INFO - 'tart', 22:32:30 INFO - 'tscrollx', 22:32:30 INFO - 'cart']}, 22:32:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:32:30 INFO - 'tsvgr_opacity', 22:32:30 INFO - 'tart', 22:32:30 INFO - 'tscrollx', 22:32:30 INFO - 'cart']}, 22:32:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'talos_options': ['--disable-e10s'], 22:32:30 INFO - 'tests': ['tp5o']}, 22:32:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'tests': ['tp5o']}, 22:32:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'talos_options': ['--disable-e10s', 22:32:30 INFO - '--xperf_path', 22:32:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:32:30 INFO - 'tests': ['tp5n']}, 22:32:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:32:30 INFO - 'talos_options': ['--xperf_path', 22:32:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:32:30 INFO - 'tests': ['tp5n']}}, 22:32:30 INFO - 'talos.zip': {'path': '', 22:32:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:32:30 INFO - Running post-action listener: _resource_record_post_action 22:32:30 INFO - [mozharness: 2016-11-15 06:32:30.049225Z] Finished populate-webroot step (success) 22:32:30 INFO - [mozharness: 2016-11-15 06:32:30.049355Z] Running create-virtualenv step. 22:32:30 INFO - Running pre-action listener: _resource_record_pre_action 22:32:30 INFO - Running main action method: create_virtualenv 22:32:30 INFO - Creating virtualenv /builds/slave/test/build/venv 22:32:30 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 22:32:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:32:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:32:30 INFO - Using real prefix '/tools/python27' 22:32:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:32:30 INFO - Installing distribute.............................................................................................................................................................................................done. 22:32:33 INFO - Installing pip.................done. 22:32:33 INFO - Return code: 0 22:32:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:32:34 INFO - Reading from file tmpfile_stdout 22:32:34 INFO - Output received: 22:32:34 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) 22:32:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:34 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 22:32:34 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 22:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:34 INFO - 'HOME': '/Users/cltbld', 22:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:34 INFO - 'LOGNAME': 'cltbld', 22:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:34 INFO - 'MOZ_NO_REMOTE': '1', 22:32:34 INFO - 'NO_EM_RESTART': '1', 22:32:34 INFO - 'PAGER': '/bin/cat', 22:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:34 INFO - 'PWD': '/builds/slave/test', 22:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:34 INFO - 'SHELL': '/bin/bash', 22:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:34 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:34 INFO - 'USER': 'cltbld', 22:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:34 INFO - 'XPC_FLAGS': '0x0', 22:32:34 INFO - 'XPC_SERVICE_NAME': '0', 22:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:34 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 22:32:34 INFO - Cleaning up... 22:32:34 INFO - Return code: 0 22:32:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:34 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:34 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 22:32:34 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 22:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:34 INFO - 'HOME': '/Users/cltbld', 22:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:34 INFO - 'LOGNAME': 'cltbld', 22:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:34 INFO - 'MOZ_NO_REMOTE': '1', 22:32:34 INFO - 'NO_EM_RESTART': '1', 22:32:34 INFO - 'PAGER': '/bin/cat', 22:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:34 INFO - 'PWD': '/builds/slave/test', 22:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:34 INFO - 'SHELL': '/bin/bash', 22:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:34 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:34 INFO - 'USER': 'cltbld', 22:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:34 INFO - 'XPC_FLAGS': '0x0', 22:32:34 INFO - 'XPC_SERVICE_NAME': '0', 22:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:34 INFO - Downloading/unpacking psutil>=3.1.1 22:32:34 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 22:32:34 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 22:32:34 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 22:32:34 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 22:32:34 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 22:32:34 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 22:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:32:38 INFO - Installing collected packages: psutil 22:32:38 INFO - Running setup.py install for psutil 22:32:38 INFO - building 'psutil._psutil_osx' extension 22:32:38 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 22:32:38 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 22:32:38 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 22:32:38 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 22:32:38 INFO - building 'psutil._psutil_posix' extension 22:32:38 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 22:32:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:32:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:32:38 INFO - ^ 22:32:38 INFO - 1 warning generated. 22:32:38 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 22:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:32:38 INFO - Successfully installed psutil 22:32:38 INFO - Cleaning up... 22:32:39 INFO - Return code: 0 22:32:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:39 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:39 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 22:32:39 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 22:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:39 INFO - 'HOME': '/Users/cltbld', 22:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:39 INFO - 'LOGNAME': 'cltbld', 22:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:39 INFO - 'MOZ_NO_REMOTE': '1', 22:32:39 INFO - 'NO_EM_RESTART': '1', 22:32:39 INFO - 'PAGER': '/bin/cat', 22:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:39 INFO - 'PWD': '/builds/slave/test', 22:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:39 INFO - 'SHELL': '/bin/bash', 22:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:39 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:39 INFO - 'USER': 'cltbld', 22:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:39 INFO - 'XPC_FLAGS': '0x0', 22:32:39 INFO - 'XPC_SERVICE_NAME': '0', 22:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:32:39 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 22:32:39 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 22:32:39 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 22:32:39 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 22:32:39 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 22:32:39 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 22:32:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:32:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:32:41 INFO - Installing collected packages: mozsystemmonitor 22:32:41 INFO - Running setup.py install for mozsystemmonitor 22:32:41 INFO - Successfully installed mozsystemmonitor 22:32:41 INFO - Cleaning up... 22:32:42 INFO - Return code: 0 22:32:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:32:42 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')]} 22:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:42 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')]} 22:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:42 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:42 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 22:32:42 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 22:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:42 INFO - 'HOME': '/Users/cltbld', 22:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:42 INFO - 'LOGNAME': 'cltbld', 22:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:42 INFO - 'MOZ_NO_REMOTE': '1', 22:32:42 INFO - 'NO_EM_RESTART': '1', 22:32:42 INFO - 'PAGER': '/bin/cat', 22:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:42 INFO - 'PWD': '/builds/slave/test', 22:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:42 INFO - 'SHELL': '/bin/bash', 22:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:42 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:42 INFO - 'USER': 'cltbld', 22:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:42 INFO - 'XPC_FLAGS': '0x0', 22:32:42 INFO - 'XPC_SERVICE_NAME': '0', 22:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:42 INFO - Downloading/unpacking jsonschema==2.5.1 22:32:42 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 22:32:42 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 22:32:42 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 22:32:42 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 22:32:42 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 22:32:42 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 22:32:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:32:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:32:44 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 22:32:44 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 22:32:44 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 22:32:44 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 22:32:44 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 22:32:44 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 22:32:45 INFO - Downloading functools32-3.2.3-2.tar.gz 22:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:32:45 INFO - warning: no files found matching '*.txt' 22:32:45 INFO - no previously-included directories found matching 'build' 22:32:45 INFO - no previously-included directories found matching 'dist' 22:32:45 INFO - no previously-included directories found matching '.git*' 22:32:45 INFO - Installing collected packages: jsonschema, functools32 22:32:45 INFO - Running setup.py install for functools32 22:32:45 INFO - warning: no files found matching '*.txt' 22:32:45 INFO - no previously-included directories found matching 'build' 22:32:45 INFO - no previously-included directories found matching 'dist' 22:32:45 INFO - no previously-included directories found matching '.git*' 22:32:45 INFO - Successfully installed jsonschema functools32 22:32:45 INFO - Cleaning up... 22:32:45 INFO - Return code: 0 22:32:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:45 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:45 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 22:32:45 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 22:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:45 INFO - 'HOME': '/Users/cltbld', 22:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:45 INFO - 'LOGNAME': 'cltbld', 22:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:45 INFO - 'MOZ_NO_REMOTE': '1', 22:32:45 INFO - 'NO_EM_RESTART': '1', 22:32:45 INFO - 'PAGER': '/bin/cat', 22:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:45 INFO - 'PWD': '/builds/slave/test', 22:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:45 INFO - 'SHELL': '/bin/bash', 22:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:45 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:45 INFO - 'USER': 'cltbld', 22:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:45 INFO - 'XPC_FLAGS': '0x0', 22:32:45 INFO - 'XPC_SERVICE_NAME': '0', 22:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:32:45 INFO - Cleaning up... 22:32:45 INFO - Return code: 0 22:32:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:45 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:45 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 22:32:45 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 22:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:45 INFO - 'HOME': '/Users/cltbld', 22:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:45 INFO - 'LOGNAME': 'cltbld', 22:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:45 INFO - 'MOZ_NO_REMOTE': '1', 22:32:45 INFO - 'NO_EM_RESTART': '1', 22:32:45 INFO - 'PAGER': '/bin/cat', 22:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:45 INFO - 'PWD': '/builds/slave/test', 22:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:45 INFO - 'SHELL': '/bin/bash', 22:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:45 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:45 INFO - 'USER': 'cltbld', 22:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:45 INFO - 'XPC_FLAGS': '0x0', 22:32:45 INFO - 'XPC_SERVICE_NAME': '0', 22:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:46 INFO - Downloading/unpacking blobuploader==1.2.4 22:32: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 22:32: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 22:32: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 22:32: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 22:32: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 22:32: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 22:32:48 INFO - Downloading blobuploader-1.2.4.tar.gz 22:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:32:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:32:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:49 INFO - Downloading docopt-0.6.1.tar.gz 22:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:32:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:32:49 INFO - Running setup.py install for blobuploader 22:32:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:32:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:32:50 INFO - Running setup.py install for requests 22:32:50 INFO - Running setup.py install for docopt 22:32:50 INFO - Successfully installed blobuploader requests docopt 22:32:50 INFO - Cleaning up... 22:32:50 INFO - Return code: 0 22:32:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:50 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:50 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 22:32:50 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 22:32:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:50 INFO - 'HOME': '/Users/cltbld', 22:32:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:50 INFO - 'LOGNAME': 'cltbld', 22:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:50 INFO - 'MOZ_NO_REMOTE': '1', 22:32:50 INFO - 'NO_EM_RESTART': '1', 22:32:50 INFO - 'PAGER': '/bin/cat', 22:32:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:50 INFO - 'PWD': '/builds/slave/test', 22:32:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:50 INFO - 'SHELL': '/bin/bash', 22:32:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:50 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:50 INFO - 'USER': 'cltbld', 22:32:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:50 INFO - 'XPC_FLAGS': '0x0', 22:32:50 INFO - 'XPC_SERVICE_NAME': '0', 22:32:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-liOyyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-7iqXRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-mvkX5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-6qSYz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-KIZp9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-cZZ9y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-02bJR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Y3qJbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Dj59x3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:32:51 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-uStRDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-LsAqdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-GGBFQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-W3Qo8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Yr3AUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-9xMATO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-t752uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:32:52 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-DXohem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:32:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:32:52 INFO - Running setup.py install for manifestparser 22:32:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:32:52 INFO - Running setup.py install for mozcrash 22:32:53 INFO - Running setup.py install for mozdebug 22:32:53 INFO - Running setup.py install for mozdevice 22:32:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Running setup.py install for mozfile 22:32:53 INFO - Running setup.py install for mozhttpd 22:32:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Running setup.py install for mozinfo 22:32:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Running setup.py install for mozInstall 22:32:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:32:53 INFO - Running setup.py install for mozleak 22:32:54 INFO - Running setup.py install for mozlog 22:32:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:32:54 INFO - Running setup.py install for moznetwork 22:32:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:32:54 INFO - Running setup.py install for mozprocess 22:32:54 INFO - Running setup.py install for mozprofile 22:32:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:32:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:32:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:32:54 INFO - Running setup.py install for mozrunner 22:32:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:32:54 INFO - Running setup.py install for mozscreenshot 22:32:55 INFO - Running setup.py install for moztest 22:32:55 INFO - Running setup.py install for mozversion 22:32:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:32:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:32:55 INFO - Cleaning up... 22:32:55 INFO - Return code: 0 22:32:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:32:55 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')]} 22:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:55 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')]} 22:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:32:55 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:32:55 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 22:32:55 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 22:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:32:55 INFO - 'HOME': '/Users/cltbld', 22:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:32:55 INFO - 'LOGNAME': 'cltbld', 22:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:32:55 INFO - 'MOZ_NO_REMOTE': '1', 22:32:55 INFO - 'NO_EM_RESTART': '1', 22:32:55 INFO - 'PAGER': '/bin/cat', 22:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:32:55 INFO - 'PWD': '/builds/slave/test', 22:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:32:55 INFO - 'SHELL': '/bin/bash', 22:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:32:55 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:32:55 INFO - 'USER': 'cltbld', 22:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:32:55 INFO - 'XPC_FLAGS': '0x0', 22:32:55 INFO - 'XPC_SERVICE_NAME': '0', 22:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:32:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-r4Drae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:32:55 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)) 22:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:32:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-j7wPqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:32:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ye1P_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:32:55 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)) 22:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:32:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-opnP6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-7G96Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Q1gHzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-mAF1vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-FRU1GW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-jZ6H08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-uMWGWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-7tGk5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-shKiMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-yXv2U1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:32:56 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)) 22:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:32:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-zlypcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:57 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-TYPszO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:32:57 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)) 22:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:32:57 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-JiHsQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:32:57 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)) 22:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:32:57 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-vF9nLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:32:57 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)) 22:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:32:57 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)) 22:32:57 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)) 22:32:57 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)) 22:32:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:32:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:32:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:00 INFO - Downloading blessings-1.6.tar.gz 22:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:33:00 INFO - Installing collected packages: blessings 22:33:00 INFO - Running setup.py install for blessings 22:33:00 INFO - Successfully installed blessings 22:33:00 INFO - Cleaning up... 22:33:00 INFO - Return code: 0 22:33:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:33:00 INFO - Reading from file tmpfile_stdout 22:33:00 INFO - Current package versions: 22:33:00 INFO - blessings == 1.6 22:33:00 INFO - blobuploader == 1.2.4 22:33:00 INFO - docopt == 0.6.1 22:33:00 INFO - functools32 == 3.2.3-2 22:33:00 INFO - jsonschema == 2.5.1 22:33:00 INFO - manifestparser == 1.1 22:33:00 INFO - mozInstall == 1.12 22:33:00 INFO - mozcrash == 1.0 22:33:00 INFO - mozdebug == 0.1 22:33:00 INFO - mozdevice == 0.48 22:33:00 INFO - mozfile == 1.2 22:33:00 INFO - mozhttpd == 0.7 22:33:00 INFO - mozinfo == 0.9 22:33:00 INFO - mozleak == 0.1 22:33:00 INFO - mozlog == 3.4 22:33:00 INFO - moznetwork == 0.27 22:33:00 INFO - mozprocess == 0.23 22:33:00 INFO - mozprofile == 0.28 22:33:00 INFO - mozrunner == 6.13 22:33:00 INFO - mozscreenshot == 0.1 22:33:00 INFO - mozsystemmonitor == 0.3 22:33:00 INFO - moztest == 0.7 22:33:00 INFO - mozversion == 1.4 22:33:00 INFO - psutil == 3.1.1 22:33:00 INFO - requests == 1.2.3 22:33:00 INFO - wsgiref == 0.1.2 22:33:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:01 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:33:01 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 22:33:01 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 22:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:01 INFO - 'HOME': '/Users/cltbld', 22:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:01 INFO - 'LOGNAME': 'cltbld', 22:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:01 INFO - 'MOZ_NO_REMOTE': '1', 22:33:01 INFO - 'NO_EM_RESTART': '1', 22:33:01 INFO - 'PAGER': '/bin/cat', 22:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:01 INFO - 'PWD': '/builds/slave/test', 22:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:01 INFO - 'SHELL': '/bin/bash', 22:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:33:01 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:01 INFO - 'USER': 'cltbld', 22:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:01 INFO - 'XPC_FLAGS': '0x0', 22:33:01 INFO - 'XPC_SERVICE_NAME': '0', 22:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 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)) 22:33:01 INFO - Cleaning up... 22:33:01 INFO - Return code: 0 22:33:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:01 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 0x110582490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bc90df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, '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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:33:01 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 22:33:01 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 22:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:01 INFO - 'HOME': '/Users/cltbld', 22:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:01 INFO - 'LOGNAME': 'cltbld', 22:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:01 INFO - 'MOZ_NO_REMOTE': '1', 22:33:01 INFO - 'NO_EM_RESTART': '1', 22:33:01 INFO - 'PAGER': '/bin/cat', 22:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:01 INFO - 'PWD': '/builds/slave/test', 22:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:01 INFO - 'SHELL': '/bin/bash', 22:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:33:01 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:01 INFO - 'USER': 'cltbld', 22:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:01 INFO - 'XPC_FLAGS': '0x0', 22:33:01 INFO - 'XPC_SERVICE_NAME': '0', 22:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:33:01 INFO - Cleaning up... 22:33:01 INFO - Return code: 0 22:33:01 INFO - Running post-action listener: _resource_record_post_action 22:33:01 INFO - Running post-action listener: _start_resource_monitoring 22:33:01 INFO - Starting resource monitoring. 22:33:01 INFO - [mozharness: 2016-11-15 06:33:01.793232Z] Finished create-virtualenv step (success) 22:33:01 INFO - [mozharness: 2016-11-15 06:33:01.794266Z] Running install step. 22:33:01 INFO - Running pre-action listener: _resource_record_pre_action 22:33:01 INFO - Running main action method: install 22:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:33:02 INFO - Reading from file tmpfile_stdout 22:33:02 INFO - Detecting whether we're running mozinstall >=1.0... 22:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:33:02 INFO - Reading from file tmpfile_stdout 22:33:02 INFO - Output received: 22:33:02 INFO - Usage: mozinstall [options] installer 22:33:02 INFO - Options: 22:33:02 INFO - -h, --help show this help message and exit 22:33:02 INFO - -d DEST, --destination=DEST 22:33:02 INFO - Directory to install application into. [default: 22:33:02 INFO - "/builds/slave/test"] 22:33:02 INFO - --app=APP Application being installed. [default: firefox] 22:33:02 INFO - mkdir: /builds/slave/test/build/application 22:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 22:33:34 INFO - Reading from file tmpfile_stdout 22:33:34 INFO - Output received: 22:33:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:33:34 INFO - Running post-action listener: _resource_record_post_action 22:33:34 INFO - [mozharness: 2016-11-15 06:33:34.251122Z] Finished install step (success) 22:33:34 INFO - [mozharness: 2016-11-15 06:33:34.251297Z] Running run-tests step. 22:33:34 INFO - Running pre-action listener: _resource_record_pre_action 22:33:34 INFO - Running main action method: run_tests 22:33:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:33:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:33:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:33:34 INFO - 2016-11-14 22:33:34.265 screenresolution[2175:24724] starting screenresolution argv=screenresolution get 22:33:34 INFO - 2016-11-14 22:33:34.268 screenresolution[2175:24724] Display 0: 1600x1200x32@60 22:33:34 INFO - 2016-11-14 22:33:34.274 screenresolution[2176:24726] starting screenresolution argv=screenresolution list 22:33:34 INFO - Available Modes on Display 0 22:33:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:33:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:33:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:33:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:33:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:33:34 INFO - Intel Iris: 22:33:34 INFO - Chipset Model: Intel Iris 22:33:34 INFO - Type: GPU 22:33:34 INFO - Bus: Built-In 22:33:34 INFO - VRAM (Dynamic, Max): 1536 MB 22:33:34 INFO - Vendor: Intel (0x8086) 22:33:34 INFO - Device ID: 0x0a2e 22:33:34 INFO - Revision ID: 0x0009 22:33:34 INFO - Displays: 22:33:34 INFO - Display: 22:33:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:33:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:33:34 INFO - Display Serial Number: 1600x1200 60 22:33:34 INFO - Main Display: Yes 22:33:34 INFO - Mirror: Off 22:33:34 INFO - Online: Yes 22:33:34 INFO - Rotation: Supported 22:33:34 INFO - Return code: 0 22:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:33:34 INFO - Python 2.7.3 22:33:34 INFO - Return code: 0 22:33:34 INFO - grabbing minidump binary from tooltool 22:33:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:34 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 0x110396d30>, '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 0x1101fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11038eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:33:34 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 22:33:34 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 22:33:34 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 22:33:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:33:34 INFO - Return code: 0 22:33:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:33:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:33:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:33:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:33:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZbIBZtYC3x/Render', 22:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:34 INFO - 'HOME': '/Users/cltbld', 22:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:34 INFO - 'LOGNAME': 'cltbld', 22:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:34 INFO - 'MOZ_NO_REMOTE': '1', 22:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:33:34 INFO - 'NO_EM_RESTART': '1', 22:33:34 INFO - 'PAGER': '/bin/cat', 22:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:34 INFO - 'PWD': '/builds/slave/test', 22:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:34 INFO - 'SHELL': '/bin/bash', 22:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4lbtWtEubk/Listeners', 22:33:34 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 22:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:34 INFO - 'USER': 'cltbld', 22:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:34 INFO - 'XPC_FLAGS': '0x0', 22:33:34 INFO - 'XPC_SERVICE_NAME': '0', 22:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:33:34 INFO - mozversion application_buildid: 20161114200813 22:33:34 INFO - mozversion application_changeset: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 22:33:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:33:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:33:34 INFO - mozversion application_name: Firefox 22:33:34 INFO - mozversion application_remotingname: firefox-dev 22:33:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:33:34 INFO - mozversion application_vendor: Mozilla 22:33:34 INFO - mozversion application_version: 52.0a2 22:33:34 INFO - mozversion platform_buildid: 20161114200813 22:33:34 INFO - mozversion platform_changeset: d32b3458265589c2e9bc2f9413b5a3256a6f9ea3 22:33:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:33:34 INFO - mozversion platform_version: 52.0a2 22:33:34 INFO - using testdate: 1479191614 22:33:34 INFO - actual date: 1479191614 22:33:34 INFO - starting webserver on 'localhost:56551' 22:33:34 INFO - SUITE-START | Running 2 tests 22:33:34 INFO - TEST-START | dromaeo_css 22:33:34 INFO - operating with platform_type : mac_ 22:33:34 INFO - Initialising browser for dromaeo_css test... 22:33:34 INFO - TEST-INFO | started process 2186 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp03jBRl/profile http://localhost:56551/getInfo.html) 22:33:36 ERROR - PROCESS | 2186 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 22:33:37 INFO - PROCESS | 2186 | __metrics Screen width/height:1600/1200 22:33:37 INFO - PROCESS | 2186 | colorDepth:24 22:33:37 INFO - PROCESS | 2186 | Browser inner width/height: 1024/689 22:33:37 INFO - PROCESS | 2186 | __metrics 22:33:37 INFO - PROCESS | 2186 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:33:37 INFO - PROCESS | 2186 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:33:37 INFO - TEST-INFO | 2186: exit 0 22:33:37 INFO - Browser initialized. 22:33:37 INFO - Running cycle 1/1 for dromaeo_css test... 22:33:37 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp03jBRl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:33:49 INFO - PROCESS | 2188 | RSS: Main: 217624576 22:33:49 INFO - PROCESS | 2188 | 22:34:41 INFO - PROCESS | 2188 | 1479191681094 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] 22:36:52 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56551/tests/dromaeo/cssquery-ext.html) 22:36:56 INFO - PROCESS | 2188 | RSS: Main: 456323072 22:36:56 INFO - PROCESS | 2188 | 22:39:58 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-ext.html (next: http://localhost:56551/tests/dromaeo/cssquery-jquery.html) 22:39:59 INFO - PROCESS | 2188 | RSS: Main: 351457280 22:39:59 INFO - PROCESS | 2188 | 22:43:01 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56551/tests/dromaeo/cssquery-mootools.html) 22:43:02 INFO - PROCESS | 2188 | RSS: Main: 340381696 22:43:02 INFO - PROCESS | 2188 | 22:46:04 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56551/tests/dromaeo/cssquery-prototype.html) 22:46:05 INFO - PROCESS | 2188 | RSS: Main: 318693376 22:46:05 INFO - PROCESS | 2188 | 22:49:08 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56551/tests/dromaeo/cssquery-yui.html) 22:49:09 INFO - PROCESS | 2188 | RSS: Main: 310132736 22:49:09 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:56551/tests/dromaeo/cssquery-yui.html (next: http://localhost:56551/tests/dromaeo/cssquery-dojo.html) 22:52:12 INFO - PROCESS | 2188 | RSS: Main: 444309504 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | __start_tp_report 22:52:12 INFO - PROCESS | 2188 | _x_x_mozilla_page_load 22:52:12 INFO - PROCESS | 2188 | _x_x_mozilla_page_load_details 22:52:12 INFO - PROCESS | 2188 | |i|pagename|runs| 22:52:12 INFO - PROCESS | 2188 | |0;dojo.html;2744.8353098814086;3232.4666430039047;3275.115718755936;3282.261491164487;3328.7836290641108;4676.906461870764;4686.4610569784445;5612.326520817503;5654.434556544344;5686.800961966332;5664.178694089354;5666.9433305666935;5693.658380497173;5707.77168913243;5708.65748055117;5141.871453213672;5174.379074511064;5177.637565370425;5260.92108618371;5295.152775555912;5126.256692779545;5145.897082058375;5174.327337446127;5241.8427447176455;5275.630705850592;5161.561267292284;5194.935038317155;5199.506046925536;5221.95560887822;5236.528712415882;5199.714015729133;5201.843944681666;5211.7133557654315;5247.16045432729;5261.1319132343115;5118.283440318359;5197.376314842205;5206.401263854629;5207.505286997729;5259.921101183485;5530.640508366983;5533.363663178706;5587.106063029895;5596.58025648078;5635.887282254373;6132.336724663555;6144.385561443864;6168.907466388007;6176.320604733476;6214.751409943597;5225.372955245395;5689.288838895138;6334.2398912130375;6338.873222535523;6345.49236061114;5618.101103823368;5686.715664216773;5688.146777983336;5741.25363702716;5801.970990145064;6312.495000399957;6329.651869147241;6374.776882809079;6380.617162970237;6385.648789316632;6143.477804386677;6169.5064394848305;6172;6177.4749146322165;6191.566590338633;6095.817125486243;6103.6032657877095;6118.131909153758;6273.466755325757;6274.466670332981;6133.355997620275;6188.040853667689;6195.96902015487;6261.624302541862;6276.089966954766;6235.968820155961;6266.530010249249;6274.40393162658;6287.811365659038;6296.968515157487;4905.337779399735;4937.012597480519;4937.061958227925;4947.802087916443;4948.703077815343;4352.978235108868;4392.934105988413;4396.846110386121;4405.074934263776;4409.889752756207;5307.044731948209;5411.107167317348;5768.163616275617;5790;5806.216160818235;6274.937250627435;6282.057691346335;6284.905726414107;6295.401936815995;6305.936940630627;5476.041692703761;5512.614117011851;5527.695976721238;5703.116017017369;5754.74103665336;5324.893502130013;5336.67979921206;5387.703676297841;5398.64908780928;5458.645188062843;5235.869103272448;6355.3644635536075;6360.9045864312075;6364.96817515928;6369.649669268235;6036.6679832609625;6286.214223222098;6292.087647291026;6304.401081897213;6310.6213627182515;6231.096491008869;6242.875142497034;6255.530835187288;6288.151099601495;6308.810735677937;4947.332110165082;6142.201513803177;6185.134081228722;6197.008478643395;6209.627422354673;6229.4393504584805;6289.591176573508;6295.464885484602;6301.810945671454;6304.495640348853;5922.763089476545;5950.702464876652;5959.28488581373;6047.758089676364;6050.42520960508;6036.245469316335;6048.516118710405;6049.758009679739;6073.544484163617;6075.3317135115985;5859.296884373903;5865.442782935615;5871.207387002871;5873.207117039145;5876.6180198286975;5205.739713014258;5361.38344090419;6053.545984051302;6057.273127224761;6062.514998799998;5848;5860.560457965585;5871.119332100219;5877.823665290048;5884.558658100574 22:52:12 INFO - PROCESS | 2188 | |1;ext.html;445.07424556921603;476.0906668263615;518.3287642502959;526.465637378061;546.3471151973391;30504;39590.40614390783;40351.79824100879;40707.982300442505;42761.35857962133;41660.166796664125;41979.160416791645;42016.78991605038;42034;42047.15905681885;9332.720018399443;9509;9510.762230944212;9517.381370210915;9538.332316737797;9392.436453812794;9485.668001619944;9502.71491855242;9502.762430939212;9518.952405237964;8710.956445217766;8837.160469755363;9436.764080897998;9520.666776662818;9584.281178911575;9430.622775089023;9501.334906556544;9506.096920792514;9506.287028472892;9518;9379.155875971128;9452.19656329213;9467.242620590336;9470.668526601536;9472.289578281587;39536.40695389571;39619.00952476182;39897;39928.001799954945;40016.59983400158;3262.722668573174;3293.4236508610898;3316.519104729825;3320.9833950830093;3341.732661387083;23790.64314035291;30093.398132037455;30582.84708576465;30827.537586936214;31708.682913170804;3175.095097897088;3192.425363434581;3199.840007999614;3242.643309235982;3270.656581058978;489934;490549;490978.5451072758;491604;492032;61470.69264653648;61702;61832;61834.69082654558;61839.38160618426;34479.4828077579;34624;34986.12534686653;36340;61122.694386527786;16301.918490407472;16434.6713065738;16443.424480143378;16445.91777041107;16475;12447.813282800766;12533.060020498498;12563;12578.685532861751;12587.433565489575;19360.654186524924;19500.17914725915;19543.652277997884;19583.306263399423;19591.844897061314;6930.93069069313;7331.633418329169;7618.847623047508;7684;7745.148033716287;700.6356694518923;944.7779771753633;960.7357976556503;1003.934744241622;1031.0668844695563;24176.21973473073;28336.574951375747;29677.40645187084;29855.701442985726;30538.847305763775;988.3032462114193;997.4414327976357;1021.1830535571513;1041.1878734587035;1151.240181480219;12498.625041248777;14879.33043013067;15416.229188540752;15463.072215667096;16017.878748487494;657946;662783;664253;664539;664645;57129.2865963711;57509;57515;57572.71213643905;57674.846503069704;1220.4690959432676;1266.3541593787236;1333.2667033131934;1359.8368195816565;1377.7520046391746;30072.84963575168;31844.68155318417;32071.071958862933;32075.198120047186;33622.83188584042;56588;57405;57594.13608795955;59947;60002.09996850138;604291;611740;612297;612937;613726;43192.56807431886;44098.11803763843;45011;45096.77451612721;45207;44857.55142448534;46506.06987860292;48246.758766205945;48325.75837120792;49045.75477122592;39854.80435586937;40042.39936401015;41028.1794364117;41762.791186043876;41824.95437613962;32342.514862276614;38492.42261366026;39123.80438097791;39382.409263861635;39977.80011099926;39117.80441097776;39667.603323967545;40007.79976600707;40103.3984490227;40130.79934600425;29964.700352997064;32806.34387312288;34349.65650343464;34733.826330868185;34920;25969.22092337233;26036.34909127287;26179.69101544953;26203.4759304809;26776 22:52:12 INFO - PROCESS | 2188 | |2;jquery.html;339.03375380166545;367.346123899459;375.81021584100023;379.4062292512218;404.87646780184986;14874.702505949874;15011.849881501195;15931.920340398296;20249.39251822444;20322.187112515483;19359.51601209967;19789;20293.492662683402;20426.182952681876;20639.484012899647;5666.121751128571;5788.105106749047;5959.910601340962;5969.686668932828;5972.134040564138;4561;5812.6512409255365;5902.97048514759;5905.173275741399;5907.202527658776;5510.531604813596;5844.415558444143;5853.473187413132;5855.033919403293;5862.912056319158;5743.425657434265;5888.381719919433;5890.057590785496;5890.882182356372;5897.587168898201;4661.836835710734;5717.142428635696;5721.6853073080965;5876.206712093898;5919.1713160157615;12844.614661560168;13102.868971310356;13102.868971310356;13113.213207207598;23700.288991330286;2386.5465561543424;2414.794742446894;2448.7428819973825;2779.5691667791525;2795.161451564543;9506.334556581041;9824.32986392315;10154.441505717183;10297.691069267934;10444.582216711322;1747.5456381340816;1838.5495553589321;1972.8323092537153;2009.2465325502938;2041.8468614853796;114656.4267178667;118752.40623796826;119460.99999999913;126180.36909815393;143513;20070.698939515918;20342.898285508774;20821.479463013548;20966;21358;35154.64845351514;35301.82349088238;35797.82101089478;36066.63933360685;36092.45861312083;10713.83929241062;10745.570177192825;10814.78370432587;11026.117910567136;11034.448277586089;7517.887231691529;8797.736067917973;8957.14907083826;8991.820163596822;9196.586153623102;9468.431894086378;9538.093881081286;9584.185344245818;9607.205153964145;9653.806923861624;3211.6627754085484;3539.6460353964912;3655.4882316475714;3661.761985470936;3666.2686298707636;775.5734346109617;816.7223144131024;877.9912200878038;966.7051549277538;967.3035414501547;19933.60132797365;20520.692189617166;20650.89674551648;20832;20988.895055524623;903.5572569441058;915.4598786715869;920.3189639666598;936.101342711005;940.4545363689044;10164.796704066026;10281.280310378203;10726.304962201833;10870.021698047214;11085.667429977113;523236;562930;620632;627954;642404;122504;122598;128422.35788821183;133253.3337333307;136321.3183934074;981.5141504955093;985.2610542093287;1017.3285631483186;1018.8013337399136;1019.5463018956493;17871.463856084338;18450.907745461187;18744.718829217847;19069.618607627493;19156.521086972938;51410.97178056493;53091;54699.72650136724;55488;56262;77605;77881;78697;78937;79391;7721.575313357819;10640.84038739435;11693.415329233674;12028.398580070787;12400;12466.87533124657;12978.545750898673;13556.59330220095;13766.036377453482;13828.377723002288;8278.461899976483;11497.54009839597;11703.2978021319;12013;12028.518859245532;7248.56508609464;7802.687892484237;8073.798155046171;8106.310963567927;8240.340772738047;7883.6846526138315;7935.444518883623;7964.044314682275;8364.581770911309;8534.231919127309;12148.817767733315;12351.567695130627;12520.937395312965;12806.295653738762;12812.55156069533;10087.193024558164;11571.6528504145;11804;11913.642590722293;12179.330136842558 22:52:12 INFO - PROCESS | 2188 | |3;mootools.html;1332.0409305300182;1377.2975782350998;1496.3341313115661;1497.3860717105988;1821.7085266357378;7407.111146662402;7761.6507257173425;7987.800304992371;8300.83398332033;8301.750947471586;8042;8075.353971682266;8159.91840081599;8169.060558035812;8169.183081691828;2256.311824893403;2278.396225000368;2289;2293.3120063980823;2293.4610366563843;2270.421042634134;2314.143766806276;2316.258797184893;2321.141177764221;2322.988385058081;2256.9435764105947;2271.2391348898054;2289.164454973931;2289.2445492987276;2292.541491701658;2204.239537359608;2236.3626366485637;2263.7849404306567;2266.7959883610556;2271.0234599122286;2253.8196944244423;2279.9316020519245;2280.85174463661;2281.4296425893526;2286.9885650571805;7956.085050219241;8132.552709600969;8212.014558253048;8227.465214761081;8227.506349619041;4931.605471562302;5119.436861945183;5126.410462796789;5126.948730512721;5134.717590532519;2910.3306239565018;2946.484365236075;3345.381104495676;3529.400001999666;3531.8057506837113;1039.469870366111;1043.7964596903607;1062.2935747727752;1080.5353697909895;1093.2128867215672;10583.84124238137;10648.680539583826;10721.785564288748;10762.461876906202;10848.78302433947;4182.309918863414;4213.304804707189;4343.739375637472;4351.369051487517;4362.541933097043;8269.09040005599;8283.50298982063;8303.501789892625;8310.792230194173;8311.25198732117;4947.851564453073;4952.009598080398;4953.306537084739;4953.306537084811;4955.40535135786;3887.3391523441082;4165;4202.2646036943415;4312.37470566766;4598.1033698429155;3567.2865426914723;3586.336527742376;3655.250673611903;3656.213914008501;3656.6160553142067;1130.3387518301886;1142.400239874056;1144.66804626659;1152.176194021279;1156.653004098767;747.8707152200108;782.7534326687189;842.1410161635098;883.6553744039829;900.243795212012;4563.520830312836;4629.768511574408;5213.087709650796;5376.327959005124;5441.183822426588;419.9853005144805;430.9396684464177;432.35794844655663;437.63676148796424;450.85572616762977;3210.4542227820857;3647.343478173901;3661.7986010769664;3696.1129328961392;3804.1060350817534;8623.698170564126;9520.333576649567;9890.35712678688;9972.501374931224;9992.15066719337;9608.471534065553;9862.802743945082;9874.901250987397;9914.206863450907;9937.155341796028;7334.449916256195;7519.699212031457;7608.31525162738;7627.961860190774;7629.427792915553;5168.3797944245425;5278.2346559749385;5549.223108764695;5610.270664813548;5645.633033852787;8518.36112291593;8824.647014119364;9042.773930651787;9050.547472626475;9336.346455748033;8473.788155296166;9202.401843880127;9311.813763724824;9392.060793920553;9419.529023548657;6406.711697973696;7141.9642901785155;7448.106227252764;7677.731279405418;7747.573883436465;7588.27911348421;7754.224577542426;7851.764447066597;7852.646630901509;7855.293023627901;6998.860022799414;7056.929430705833;7095.325944035309;7097.574145551283;7113.217546070032;6849.24658287578;6899.413549848319;6941.409980151541;6983.6857341418745;7086.539374940612;6585.5719378240265;6610.603363798187;6625.503087268377;6695.4308883745425;6705.430038446591;4610.094243328163;4642.373279607345;4665.2302370108555;4700.55344742249;4728.952710472851;4517.367568540472;4619.467613840449;4624.9768751156025;4798.832040878552;4876.707397556158 22:52:12 INFO - PROCESS | 2188 | |4;prototype.html;299.232468717739;304.09380047458575;308.114171756201;313.6299166982961;313.6393147879939;4409.581089796472;4879.585235254999;5345.037893179227;5353.35759708835;5410.648307859987;4956.281339205813;5025.271335656327;5274.05067087924;5342.946570534294;5355.410904800468;3328.484084966834;3897.8051097445154;3916.3955969280396;3970.4464504268662;4009.6190861868076;4064.2887494688457;4085.12169883475;4099.323611604082;4100.4418321037965;4120.752754834704;4033.8184781684895;4037;4063.878083657495;4072.4705788247634;4077.9592204078026;3839.7696138231654;4020.8994775130564;4036.5156181258435;4037.2934736421007;4095.610916962884;2851.672057713348;3439;3487.250241198129;3493.179102910814;3517.402041652925;4519.954800451987;4796.44840843304;5210.869728256786;5237.580993520509;5266.657667251617;3959.3467077932105;4265.87202383929;4614.884627884297;4665.323528088442;4679.532046795327;5286.867828304285;5477.589180811416;5583.408728512395;5584.342255822443;5614.971925140389;3426.697854815154;3607.531020967284;3810.2379524095013;3813.31360355136;3831.8467261309515;5184.9740751296;5401.865608222317;5793.275840519935;5802.82591522255;5856.297244330665;3617.6382361763613;3869.5113668145377;3888.786116763605;3910.354791459434;3928.724989250782;3946.546147193052;3946.9210615787524;3955.4462375267485;3956.7625942443547;3972.880813575597;4027.751397066919;4368.716033457815;4601.838935637236;4626.236670949255;4634.536546345338;4629.8842528936375;5413.512783849394;5625.409332020161;5649.435056494318;5659.971700141473;1101.4162493878257;1255.5856567332758;1259.3691169934957;1266.4870727355435;1272.2684456437585;717.6017310392782;745.9593866556232;825.4469505431375;846.3990566697713;859.5788063848669;5087.974560127176;5288.788448462095;5320.0365727533235;5385.7576409061685;5396.514313711785;4899.559039686445;5592.937341905025;5849.298084229835;5868.67722275279;5891.114843250119;5130.30740849988;5238.528532432099;5292.686460534652;5303.7348132593215;5314.61819926814;2843.7582805461766;2851.70057144001;2854.160110689835;2884.5528943013446;2919.6715494449973;5332.253889247041;5547.667139971614;5575.52608028314;5598.188262701886;5713.315081633021;4878.268214783771;4989.355641510006;5070.670406423571;5515.972420137874;5631.267935168484;4922.163232250455;5453.590980676386;5699.689071513492;5703.686297253608;5769.249997500298;4655.883602909955;4669.968563674813;5119.078565858182;5457.918131227955;5577.27495425592;4394.560543945579;5402.378726446509;5425.565954723692;5445.83662490126;5518.86202844916;5090.541851233259;5250.501202385767;5302.257683924331;5647.322321321468;5768.538516918553;3054.823892801243;3086.6758990305693;3104.7826652135036;3659.2864391443413;3674.7060235181657;4691.554302341272;4980.676256043345;5099.419180054195;5356.035913535601;5591.804286849941;5115.437301896863;5296.867578310573;5301.674581354661;5308.654937429209;5324.988252232063;4705;4986.351774269467;5276.841694749163;5313.867153321198;5340.652857564246;4430.889227769331;4803.567678908776;5074.802874189237;5098.719570423662;5115.189184057901;4578;4578.855286178456;5298.602604804578;5443.673379597237;5528.889422211614;4382.671299652603;5477.287952566141;5521.282233309644;5527.861803454945;5535.640183388228 22:52:12 INFO - PROCESS | 2188 | |5;yui.html;582.0472315381894;700.0409439068477;765.2385876053328;774.4307933668753;787.5353541410568;13793.310334483273;14223.502177423792;14236.857631423683;15130.54608361748;15959.12224827634;14354.569362919103;14616.561503154922;14886.25568721565;14923.552293431187;14985.625359366022;795.4789612803609;826.801567623769;828.2131974624131;829.1335554345724;829.2619568583965;648.244794814041;665.5939876675225;795.7453614843253;818;827.1645637905729;870.251583638074;870.3602851903846;870.7039606533777;871.4422769427572;871.8997315308726;862.8231212601402;863.7020228021322;863.7970077031893;864.2308345572445;866.6490071521044;436.301916932908;500.17970528333564;514.8558403646985;642.1170890026214;642.7975187815823;12128.636140915787;12129.09031822608;12171.391430428532;13762.518311859138;14303.856961430356;13207.40566674502;13412.26232557209;13584.93207533956;13590.25253611051;13620.863791362059;10976.506057227443;11283.774324513464;11448;11458.598949036743;11525.36287889748;3501.6848483636595;3783.3946568549177;3787.507624008889;3802.315583195024;3825.598312177237;74189;74864.62567687126;91630;93942;95032.5745113817;10310.123639490577;10444.63443779464;10458.581656733797;10496.160307175407;10535.262531622866;20013.699794503093;20088.39734807958;20308.18767249323;20410.591788164154;20423.591528169352;7296.233895440902;7471.140818805907;7491.887621685679;7650.617469126521;7732.497387669896;4132.2561938850695;4147.129102888367;4184.665226781911;4314.985978295098;4422.977885110554;3373.5445714828184;3478.773879697862;3484.285721427101;3495.545579074704;3507.4037413639744;614.4684847606788;615.2094558492316;624.341319907502;636.2015670333701;639.6865535887382;565.7030059218943;586.7652938824505;608.613530408194;665.128681427322;672.7291669767427;8552.187542183483;8892.48746745032;9239.168474837297;9433.386829856037;9531.904680953101;563.5913962377243;568.6957477749385;640.6892657061414;655.3250152342999;665.1618960110296;3596;3773.566039905446;3844;3975.383815508601;4180.728252663567;80064.5996770024;82360.58819705863;103998.96001040043;104859;106300.46849765701;69305.3069469309;72799;74607.25392746112;89567.55216223878;106544;89351.55324223338;93008;93280.53359733293;93540.53229733808;93818.59272110923;7780.299773020455;8003.19968003195;8004.959355283776;8072.515649061075;8370.330373570221;53427;54868.72565637146;55687.44312556823;56003.159952601556;57471.71264143653;40538.59461405466;54035.45964540462;63058.684706576176;72396.27603723896;83566.58216708878;10862;10867.945660271964;10965.725856853642;11201.979450285231;11483;11821.645350639494;12031.398430078287;12199.390030498616;12339.383030848243;12581.119321647397;9699.418034917928;10241.641542545975;11897.524099035942;11914.404279785802;12137.635870923887;11608.24546404481;11773.646790596296;11863.228890122115;11877.821832672344;12272;11336.376499292619;11886.465109069939;12343.629691109281;12756.808647870104;13335.133216340906;10452.216083793897;10729.785404291715;10751.892481075402;10767.084797792275;11372.374519401512;10904.945475272572;11020.135225499474;11201.495932683212;11337.922897324741;11478.82781758291 22:52:12 INFO - PROCESS | 2188 | __end_tp_report 22:52:12 INFO - PROCESS | 2188 | __start_cc_report 22:52:12 INFO - PROCESS | 2188 | _x_x_mozilla_cycle_collect,3018 22:52:12 INFO - PROCESS | 2188 | __end_cc_report 22:52:12 INFO - PROCESS | 2188 | __startTimestamp1479192732544__endTimestamp 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | ------- Summary: start ------- 22:52:12 INFO - PROCESS | 2188 | Number of tests: 6 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | [#0] dojo.html Cycles:165 Average:5678.17 Median:5859.30 stddev:675.89 (11.5%) stddev-sans-first:637.59 22:52:12 INFO - PROCESS | 2188 | Values: 2744.8 3232.5 3275.1 3282.3 3328.8 4676.9 4686.5 5612.3 5654.4 5686.8 5664.2 5666.9 5693.7 5707.8 5708.7 5141.9 5174.4 5177.6 5260.9 5295.2 5126.3 5145.9 5174.3 5241.8 5275.6 5161.6 5194.9 5199.5 5222.0 5236.5 5199.7 5201.8 5211.7 5247.2 5261.1 5118.3 5197.4 5206.4 5207.5 5259.9 5530.6 5533.4 5587.1 5596.6 5635.9 6132.3 6144.4 6168.9 6176.3 6214.8 5225.4 5689.3 6334.2 6338.9 6345.5 5618.1 5686.7 5688.1 5741.3 5802.0 6312.5 6329.7 6374.8 6380.6 6385.6 6143.5 6169.5 6172.0 6177.5 6191.6 6095.8 6103.6 6118.1 6273.5 6274.5 6133.4 6188.0 6196.0 6261.6 6276.1 6236.0 6266.5 6274.4 6287.8 6297.0 4905.3 4937.0 4937.1 4947.8 4948.7 4353.0 4392.9 4396.8 4405.1 4409.9 5307.0 5411.1 5768.2 5790.0 5806.2 6274.9 6282.1 6284.9 6295.4 6305.9 5476.0 5512.6 5527.7 5703.1 5754.7 5324.9 5336.7 5387.7 5398.6 5458.6 5235.9 6355.4 6360.9 6365.0 6369.6 6036.7 6286.2 6292.1 6304.4 6310.6 6231.1 6242.9 6255.5 6288.2 6308.8 4947.3 6142.2 6185.1 6197.0 6209.6 6229.4 6289.6 6295.5 6301.8 6304.5 5922.8 5950.7 5959.3 6047.8 6050.4 6036.2 6048.5 6049.8 6073.5 6075.3 5859.3 5865.4 5871.2 5873.2 5876.6 5205.7 5361.4 6053.5 6057.3 6062.5 5848.0 5860.6 5871.1 5877.8 5884.6 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | [#1] ext.html Cycles:180 Average:71982.45 Median:41344.17 stddev:158499.61 (383.4%) stddev-sans-first:158853.24 22:52:12 INFO - PROCESS | 2188 | Values: 445.1 476.1 518.3 526.5 546.3 30504.0 39590.4 40351.8 40708.0 42761.4 41660.2 41979.2 42016.8 42034.0 42047.2 9332.7 9509.0 9510.8 9517.4 9538.3 9392.4 9485.7 9502.7 9502.8 9519.0 8711.0 8837.2 9436.8 9520.7 9584.3 9430.6 9501.3 9506.1 9506.3 9518.0 9379.2 9452.2 9467.2 9470.7 9472.3 39536.4 39619.0 39897.0 39928.0 40016.6 3262.7 3293.4 3316.5 3321.0 3341.7 23790.6 30093.4 30582.8 30827.5 31708.7 3175.1 3192.4 3199.8 3242.6 3270.7 489934.0 490549.0 490978.5 491604.0 492032.0 61470.7 61702.0 61832.0 61834.7 61839.4 34479.5 34624.0 34986.1 36340.0 61122.7 16301.9 16434.7 16443.4 16445.9 16475.0 12447.8 12533.1 12563.0 12578.7 12587.4 19360.7 19500.2 19543.7 19583.3 19591.8 6930.9 7331.6 7618.8 7684.0 7745.1 700.6 944.8 960.7 1003.9 1031.1 24176.2 28336.6 29677.4 29855.7 30538.8 988.3 997.4 1021.2 1041.2 1151.2 12498.6 14879.3 15416.2 15463.1 16017.9 657946.0 662783.0 664253.0 664539.0 664645.0 57129.3 57509.0 57515.0 57572.7 57674.8 1220.5 1266.4 1333.3 1359.8 1377.8 30072.8 31844.7 32071.1 32075.2 33622.8 56588.0 57405.0 57594.1 59947.0 60002.1 604291.0 611740.0 612297.0 612937.0 613726.0 43192.6 44098.1 45011.0 45096.8 45207.0 44857.6 46506.1 48246.8 48325.8 49045.8 39854.8 40042.4 41028.2 41762.8 41825.0 32342.5 38492.4 39123.8 39382.4 39977.8 39117.8 39667.6 40007.8 40103.4 40130.8 29964.7 32806.3 34349.7 34733.8 34920.0 25969.2 26036.3 26179.7 26203.5 26776.0 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | [#2] jquery.html Cycles:180 Average:35960.25 Median:17746.84 stddev:99757.99 (562.1%) stddev-sans-first:100001.98 22:52:12 INFO - PROCESS | 2188 | Values: 339.0 367.3 375.8 379.4 404.9 14874.7 15011.8 15931.9 20249.4 20322.2 19359.5 19789.0 20293.5 20426.2 20639.5 5666.1 5788.1 5959.9 5969.7 5972.1 4561.0 5812.7 5903.0 5905.2 5907.2 5510.5 5844.4 5853.5 5855.0 5862.9 5743.4 5888.4 5890.1 5890.9 5897.6 4661.8 5717.1 5721.7 5876.2 5919.2 12844.6 13102.9 13102.9 13113.2 23700.3 2386.5 2414.8 2448.7 2779.6 2795.2 9506.3 9824.3 10154.4 10297.7 10444.6 1747.5 1838.5 1972.8 2009.2 2041.8 114656.4 118752.4 119461.0 126180.4 143513.0 20070.7 20342.9 20821.5 20966.0 21358.0 35154.6 35301.8 35797.8 36066.6 36092.5 10713.8 10745.6 10814.8 11026.1 11034.4 7517.9 8797.7 8957.1 8991.8 9196.6 9468.4 9538.1 9584.2 9607.2 9653.8 3211.7 3539.6 3655.5 3661.8 3666.3 775.6 816.7 878.0 966.7 967.3 19933.6 20520.7 20650.9 20832.0 20988.9 903.6 915.5 920.3 936.1 940.5 10164.8 10281.3 10726.3 10870.0 11085.7 523236.0 562930.0 620632.0 627954.0 642404.0 122504.0 122598.0 128422.4 133253.3 136321.3 981.5 985.3 1017.3 1018.8 1019.5 17871.5 18450.9 18744.7 19069.6 19156.5 51411.0 53091.0 54699.7 55488.0 56262.0 77605.0 77881.0 78697.0 78937.0 79391.0 7721.6 10640.8 11693.4 12028.4 12400.0 12466.9 12978.5 13556.6 13766.0 13828.4 8278.5 11497.5 11703.3 12013.0 12028.5 7248.6 7802.7 8073.8 8106.3 8240.3 7883.7 7935.4 7964.0 8364.6 8534.2 12148.8 12351.6 12520.9 12806.3 12812.6 10087.2 11571.7 11804.0 11913.6 12179.3 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | [#3] mootools.html Cycles:180 Average:5259.42 Median:4953.31 stddev:2917.60 (58.9%) stddev-sans-first:2910.85 22:52:12 INFO - PROCESS | 2188 | Values: 1332.0 1377.3 1496.3 1497.4 1821.7 7407.1 7761.7 7987.8 8300.8 8301.8 8042.0 8075.4 8159.9 8169.1 8169.2 2256.3 2278.4 2289.0 2293.3 2293.5 2270.4 2314.1 2316.3 2321.1 2323.0 2256.9 2271.2 2289.2 2289.2 2292.5 2204.2 2236.4 2263.8 2266.8 2271.0 2253.8 2279.9 2280.9 2281.4 2287.0 7956.1 8132.6 8212.0 8227.5 8227.5 4931.6 5119.4 5126.4 5126.9 5134.7 2910.3 2946.5 3345.4 3529.4 3531.8 1039.5 1043.8 1062.3 1080.5 1093.2 10583.8 10648.7 10721.8 10762.5 10848.8 4182.3 4213.3 4343.7 4351.4 4362.5 8269.1 8283.5 8303.5 8310.8 8311.3 4947.9 4952.0 4953.3 4953.3 4955.4 3887.3 4165.0 4202.3 4312.4 4598.1 3567.3 3586.3 3655.3 3656.2 3656.6 1130.3 1142.4 1144.7 1152.2 1156.7 747.9 782.8 842.1 883.7 900.2 4563.5 4629.8 5213.1 5376.3 5441.2 420.0 430.9 432.4 437.6 450.9 3210.5 3647.3 3661.8 3696.1 3804.1 8623.7 9520.3 9890.4 9972.5 9992.2 9608.5 9862.8 9874.9 9914.2 9937.2 7334.4 7519.7 7608.3 7628.0 7629.4 5168.4 5278.2 5549.2 5610.3 5645.6 8518.4 8824.6 9042.8 9050.5 9336.3 8473.8 9202.4 9311.8 9392.1 9419.5 6406.7 7142.0 7448.1 7677.7 7747.6 7588.3 7754.2 7851.8 7852.6 7855.3 6998.9 7056.9 7095.3 7097.6 7113.2 6849.2 6899.4 6941.4 6983.7 7086.5 6585.6 6610.6 6625.5 6695.4 6705.4 4610.1 4642.4 4665.2 4700.6 4729.0 4517.4 4619.5 4625.0 4798.8 4876.7 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | [#4] prototype.html Cycles:180 Average:4418.16 Median:4987.85 stddev:1350.23 (27.1%) stddev-sans-first:1318.15 22:52:12 INFO - PROCESS | 2188 | Values: 299.2 304.1 308.1 313.6 313.6 4409.6 4879.6 5345.0 5353.4 5410.6 4956.3 5025.3 5274.1 5342.9 5355.4 3328.5 3897.8 3916.4 3970.4 4009.6 4064.3 4085.1 4099.3 4100.4 4120.8 4033.8 4037.0 4063.9 4072.5 4078.0 3839.8 4020.9 4036.5 4037.3 4095.6 2851.7 3439.0 3487.3 3493.2 3517.4 4520.0 4796.4 5210.9 5237.6 5266.7 3959.3 4265.9 4614.9 4665.3 4679.5 5286.9 5477.6 5583.4 5584.3 5615.0 3426.7 3607.5 3810.2 3813.3 3831.8 5185.0 5401.9 5793.3 5802.8 5856.3 3617.6 3869.5 3888.8 3910.4 3928.7 3946.5 3946.9 3955.4 3956.8 3972.9 4027.8 4368.7 4601.8 4626.2 4634.5 4629.9 5413.5 5625.4 5649.4 5660.0 1101.4 1255.6 1259.4 1266.5 1272.3 717.6 746.0 825.4 846.4 859.6 5088.0 5288.8 5320.0 5385.8 5396.5 4899.6 5592.9 5849.3 5868.7 5891.1 5130.3 5238.5 5292.7 5303.7 5314.6 2843.8 2851.7 2854.2 2884.6 2919.7 5332.3 5547.7 5575.5 5598.2 5713.3 4878.3 4989.4 5070.7 5516.0 5631.3 4922.2 5453.6 5699.7 5703.7 5769.2 4655.9 4670.0 5119.1 5457.9 5577.3 4394.6 5402.4 5425.6 5445.8 5518.9 5090.5 5250.5 5302.3 5647.3 5768.5 3054.8 3086.7 3104.8 3659.3 3674.7 4691.6 4980.7 5099.4 5356.0 5591.8 5115.4 5296.9 5301.7 5308.7 5325.0 4705.0 4986.4 5276.8 5313.9 5340.7 4430.9 4803.6 5074.8 5098.7 5115.2 4578.0 4578.9 5298.6 5443.7 5528.9 4382.7 5477.3 5521.3 5527.9 5535.6 22:52:12 INFO - PROCESS | 2188 | 22:52:12 INFO - PROCESS | 2188 | [#5] yui.html Cycles:180 Average:19438.76 Median:4182.70 stddev:28255.93 (675.5%) stddev-sans-first:28299.72 22:52:12 INFO - PROCESS | 2188 | Values: 582.0 700.0 765.2 774.4 787.5 13793.3 14223.5 14236.9 15130.5 15959.1 14354.6 14616.6 14886.3 14923.6 14985.6 795.5 826.8 828.2 829.1 829.3 648.2 665.6 795.7 818.0 827.2 870.3 870.4 870.7 871.4 871.9 862.8 863.7 863.8 864.2 866.6 436.3 500.2 514.9 642.1 642.8 12128.6 12129.1 12171.4 13762.5 14303.9 13207.4 13412.3 13584.9 13590.3 13620.9 10976.5 11283.8 11448.0 11458.6 11525.4 3501.7 3783.4 3787.5 3802.3 3825.6 74189.0 74864.6 91630.0 93942.0 95032.6 10310.1 10444.6 10458.6 10496.2 10535.3 20013.7 20088.4 20308.2 20410.6 20423.6 7296.2 7471.1 7491.9 7650.6 7732.5 4132.3 4147.1 4184.7 4315.0 4423.0 3373.5 3478.8 3484.3 3495.5 3507.4 614.5 615.2 624.3 636.2 639.7 565.7 586.8 608.6 665.1 672.7 8552.2 8892.5 9239.2 9433.4 9531.9 563.6 568.7 640.7 655.3 665.2 3596.0 3773.6 3844.0 3975.4 4180.7 80064.6 82360.6 103999.0 104859.0 106300.5 69305.3 72799.0 74607.3 89567.6 106544.0 89351.6 93008.0 93280.5 93540.5 93818.6 7780.3 8003.2 8005.0 8072.5 8370.3 53427.0 54868.7 55687.4 56003.2 57471.7 40538.6 54035.5 63058.7 72396.3 83566.6 10862.0 10867.9 10965.7 11202.0 11483.0 11821.6 12031.4 12199.4 12339.4 12581.1 9699.4 10241.6 11897.5 11914.4 12137.6 11608.2 11773.6 11863.2 11877.8 12272.0 11336.4 11886.5 12343.6 12756.8 13335.1 10452.2 10729.8 10751.9 10767.1 11372.4 10904.9 11020.1 11201.5 11337.9 11478.8 22:52:12 INFO - PROCESS | 2188 | -------- Summary: end -------- 22:52:12 INFO - PROCESS | 2188 | 22:52:13 INFO - TEST-INFO | 2188: exit 0 22:52:13 INFO - TEST-OK | dromaeo_css | took 1118315ms 22:52:13 INFO - TEST-START | kraken 22:52:13 INFO - operating with platform_type : mac_ 22:52:13 INFO - Initialising browser for kraken test... 22:52:13 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp2eGb9J/profile http://localhost:56551/getInfo.html) 22:52:14 ERROR - PROCESS | 2191 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 22:52:15 INFO - PROCESS | 2191 | __metrics Screen width/height:1600/1200 22:52:15 INFO - PROCESS | 2191 | colorDepth:24 22:52:15 INFO - PROCESS | 2191 | Browser inner width/height: 1024/689 22:52:15 INFO - PROCESS | 2191 | __metrics 22:52:15 INFO - PROCESS | 2191 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:52:15 INFO - PROCESS | 2191 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:52:16 INFO - TEST-INFO | 2191: exit 0 22:52:16 INFO - Browser initialized. 22:52:16 INFO - Running cycle 1/1 for kraken test... 22:52:16 INFO - TEST-INFO | started process 2192 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp2eGb9J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:52:28 INFO - PROCESS | 2192 | RSS: Main: 217223168 22:52:28 INFO - PROCESS | 2192 | 22:52:31 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-beat-detection) 22:52:32 INFO - PROCESS | 2192 | RSS: Main: 267038720 22:52:32 INFO - PROCESS | 2192 | 22:52:34 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-dft) 22:52:35 INFO - PROCESS | 2192 | RSS: Main: 276676608 22:52:35 INFO - PROCESS | 2192 | 22:52:38 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-fft) 22:52:39 INFO - PROCESS | 2192 | RSS: Main: 373903360 22:52:39 INFO - PROCESS | 2192 | 22:52:41 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56551/tests/kraken/driver.html?testName=audio-oscillator) 22:52:41 INFO - PROCESS | 2192 | RSS: Main: 277733376 22:52:41 INFO - PROCESS | 2192 | 22:52:43 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56551/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:52:44 INFO - PROCESS | 2192 | RSS: Main: 278888448 22:52:44 INFO - PROCESS | 2192 | 22:52:46 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56551/tests/kraken/driver.html?testName=imaging-darkroom) 22:52:47 INFO - PROCESS | 2192 | RSS: Main: 317153280 22:52:47 INFO - PROCESS | 2192 | 22:52:50 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56551/tests/kraken/driver.html?testName=imaging-desaturate) 22:52:51 INFO - PROCESS | 2192 | RSS: Main: 319459328 22:52:51 INFO - PROCESS | 2192 | 22:52:53 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56551/tests/kraken/driver.html?testName=json-parse-financial) 22:52:54 INFO - PROCESS | 2192 | RSS: Main: 317128704 22:52:54 INFO - PROCESS | 2192 | 22:52:56 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56551/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:52:56 INFO - PROCESS | 2192 | RSS: Main: 296189952 22:52:56 INFO - PROCESS | 2192 | 22:52:58 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:52:59 INFO - PROCESS | 2192 | RSS: Main: 298954752 22:52:59 INFO - PROCESS | 2192 | 22:53:01 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:53:01 INFO - PROCESS | 2192 | RSS: Main: 305569792 22:53:01 INFO - PROCESS | 2192 | 22:53:04 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:53:05 INFO - PROCESS | 2192 | RSS: Main: 318881792 22:53:05 INFO - PROCESS | 2192 | 22:53:08 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:53:09 INFO - PROCESS | 2192 | RSS: Main: 312414208 22:53:09 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | Cycle 1(1): loaded http://localhost:56551/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56551/tests/kraken/driver.html?testName=ai-astar) 22:53:11 INFO - PROCESS | 2192 | RSS: Main: 355966976 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | __start_tp_report 22:53:11 INFO - PROCESS | 2192 | _x_x_mozilla_page_load 22:53:11 INFO - PROCESS | 2192 | _x_x_mozilla_page_load_details 22:53:11 INFO - PROCESS | 2192 | |i|pagename|runs| 22:53:11 INFO - PROCESS | 2192 | |0;ai-astar;112;114;114;112;111;104;113;113;113;113 22:53:11 INFO - PROCESS | 2192 | |1;audio-beat-detection;146;111;104;147;113;122;139;140;143;110 22:53:11 INFO - PROCESS | 2192 | |2;audio-dft;185;184;184;187;182;183;186;184;185;182 22:53:11 INFO - PROCESS | 2192 | |3;audio-fft;79;58;58;88;60;59;58;57;82;57 22:53:11 INFO - PROCESS | 2192 | |4;audio-oscillator;84;69;94;70;67;69;89;67;68;91 22:53:11 INFO - PROCESS | 2192 | |5;imaging-gaussian-blur;88;85;88;86;89;84;90;85;88;84 22:53:11 INFO - PROCESS | 2192 | |6;imaging-darkroom;94;93;93;93;93;101;142;108;94;94 22:53:11 INFO - PROCESS | 2192 | |7;imaging-desaturate;81;84;82;82;82;83;85;86;84;84 22:53:11 INFO - PROCESS | 2192 | |8;json-parse-financial;55;54;53;53;55;58;56;56;56;57 22:53:11 INFO - PROCESS | 2192 | |9;json-stringify-tinderbox;64;71;75;78;72;71;71;73;72;73 22:53:11 INFO - PROCESS | 2192 | |10;stanford-crypto-aes;82;88;84;88;83;86;81;82;89;92 22:53:11 INFO - PROCESS | 2192 | |11;stanford-crypto-ccm;154;157;160;160;137;148;137;164;211;177 22:53:11 INFO - PROCESS | 2192 | |12;stanford-crypto-pbkdf2;167;166;167;167;166;165;168;205;160;213 22:53:11 INFO - PROCESS | 2192 | |13;stanford-crypto-sha256-iterative;66;68;71;63;58;64;86;84;87;77 22:53:11 INFO - PROCESS | 2192 | __end_tp_report 22:53:11 INFO - PROCESS | 2192 | __start_cc_report 22:53:11 INFO - PROCESS | 2192 | _x_x_mozilla_cycle_collect,2097 22:53:11 INFO - PROCESS | 2192 | __end_cc_report 22:53:11 INFO - PROCESS | 2192 | __startTimestamp1479192791527__endTimestamp 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | ------- Summary: start ------- 22:53:11 INFO - PROCESS | 2192 | Number of tests: 14 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:2.92 (2.6%) stddev-sans-first:3.10 22:53:11 INFO - PROCESS | 2192 | Values: 112.0 114.0 114.0 112.0 111.0 104.0 113.0 113.0 113.0 113.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:139.50 stddev:17.07 (12.2%) stddev-sans-first:16.74 22:53:11 INFO - PROCESS | 2192 | Values: 146.0 111.0 104.0 147.0 113.0 122.0 139.0 140.0 143.0 110.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:1.62 (0.9%) stddev-sans-first:1.69 22:53:11 INFO - PROCESS | 2192 | Values: 185.0 184.0 184.0 187.0 182.0 183.0 186.0 184.0 185.0 182.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#3] audio-fft Cycles:10 Average:65.60 Median:59.50 stddev:12.23 (20.6%) stddev-sans-first:11.97 22:53:11 INFO - PROCESS | 2192 | Values: 79.0 58.0 58.0 88.0 60.0 59.0 58.0 57.0 82.0 57.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:11.23 (14.6%) stddev-sans-first:11.61 22:53:11 INFO - PROCESS | 2192 | Values: 84.0 69.0 94.0 70.0 67.0 69.0 89.0 67.0 68.0 91.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:2.16 (2.5%) stddev-sans-first:2.24 22:53:11 INFO - PROCESS | 2192 | Values: 88.0 85.0 88.0 86.0 89.0 84.0 90.0 85.0 88.0 84.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:93.00 stddev:15.39 (16.5%) stddev-sans-first:16.14 22:53:11 INFO - PROCESS | 2192 | Values: 94.0 93.0 93.0 93.0 93.0 101.0 142.0 108.0 94.0 94.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#7] imaging-desaturate Cycles:10 Average:83.30 Median:84.00 stddev:1.57 (1.9%) stddev-sans-first:1.42 22:53:11 INFO - PROCESS | 2192 | Values: 81.0 84.0 82.0 82.0 82.0 83.0 85.0 86.0 84.0 84.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.64 (2.9%) stddev-sans-first:1.73 22:53:11 INFO - PROCESS | 2192 | Values: 55.0 54.0 53.0 53.0 55.0 58.0 56.0 56.0 56.0 57.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:3.56 (4.9%) stddev-sans-first:2.32 22:53:11 INFO - PROCESS | 2192 | Values: 64.0 71.0 75.0 78.0 72.0 71.0 71.0 73.0 72.0 73.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:87.00 stddev:3.66 (4.2%) stddev-sans-first:3.66 22:53:11 INFO - PROCESS | 2192 | Values: 82.0 88.0 84.0 88.0 83.0 86.0 81.0 82.0 89.0 92.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#11] stanford-crypto-ccm Cycles:10 Average:160.50 Median:160.00 stddev:21.47 (13.4%) stddev-sans-first:22.65 22:53:11 INFO - PROCESS | 2192 | Values: 154.0 157.0 160.0 160.0 137.0 148.0 137.0 164.0 211.0 177.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:167.00 stddev:18.46 (11.1%) stddev-sans-first:19.39 22:53:11 INFO - PROCESS | 2192 | Values: 167.0 166.0 167.0 167.0 166.0 165.0 168.0 205.0 160.0 213.0 22:53:11 INFO - PROCESS | 2192 | 22:53:11 INFO - PROCESS | 2192 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:74.00 stddev:10.45 (14.1%) stddev-sans-first:10.82 22:53:11 INFO - PROCESS | 2192 | Values: 66.0 68.0 71.0 63.0 58.0 64.0 86.0 84.0 87.0 77.0 22:53:11 INFO - PROCESS | 2192 | -------- Summary: end -------- 22:53:11 INFO - PROCESS | 2192 | 22:53:12 INFO - TEST-INFO | 2192: exit 0 22:53:12 INFO - TEST-OK | kraken | took 59226ms 22:53:12 INFO - SUITE-END | took 1177s 22:53:12 INFO - Completed test suite (00:19:37) 22:53:12 INFO - javascript benchmark 22:53:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2744.8353098814086, 3232.4666430039047, 3275.115718755936, 3282.261491164487, 3328.7836290641108, 4676.906461870764, 4686.4610569784445, 5612.326520817503, 5654.434556544344, 5686.800961966332, 5664.178694089354, 5666.9433305666935, 5693.658380497173, 5707.77168913243, 5708.65748055117, 5141.871453213672, 5174.379074511064, 5177.637565370425, 5260.92108618371, 5295.152775555912, 5126.256692779545, 5145.897082058375, 5174.327337446127, 5241.8427447176455, 5275.630705850592, 5161.561267292284, 5194.935038317155, 5199.506046925536, 5221.95560887822, 5236.528712415882, 5199.714015729133, 5201.843944681666, 5211.7133557654315, 5247.16045432729, 5261.1319132343115, 5118.283440318359, 5197.376314842205, 5206.401263854629, 5207.505286997729, 5259.921101183485, 5530.640508366983, 5533.363663178706, 5587.106063029895, 5596.58025648078, 5635.887282254373, 6132.336724663555, 6144.385561443864, 6168.907466388007, 6176.320604733476, 6214.751409943597, 5225.372955245395, 5689.288838895138, 6334.2398912130375, 6338.873222535523, 6345.49236061114, 5618.101103823368, 5686.715664216773, 5688.146777983336, 5741.25363702716, 5801.970990145064, 6312.495000399957, 6329.651869147241, 6374.776882809079, 6380.617162970237, 6385.648789316632, 6143.477804386677, 6169.5064394848305, 6172.0, 6177.4749146322165, 6191.566590338633, 6095.817125486243, 6103.6032657877095, 6118.131909153758, 6273.466755325757, 6274.466670332981, 6133.355997620275, 6188.040853667689, 6195.96902015487, 6261.624302541862, 6276.089966954766, 6235.968820155961, 6266.530010249249, 6274.40393162658, 6287.811365659038, 6296.968515157487, 4905.337779399735, 4937.012597480519, 4937.061958227925, 4947.802087916443, 4948.703077815343, 4352.978235108868, 4392.934105988413, 4396.846110386121, 4405.074934263776, 4409.889752756207, 5307.044731948209, 5411.107167317348, 5768.163616275617, 5790.0, 5806.216160818235, 6274.937250627435, 6282.057691346335, 6284.905726414107, 6295.401936815995, 6305.936940630627, 5476.041692703761, 5512.614117011851, 5527.695976721238, 5703.116017017369, 5754.74103665336, 5324.893502130013, 5336.67979921206, 5387.703676297841, 5398.64908780928, 5458.645188062843, 5235.869103272448, 6355.3644635536075, 6360.9045864312075, 6364.96817515928, 6369.649669268235, 6036.6679832609625, 6286.214223222098, 6292.087647291026, 6304.401081897213, 6310.6213627182515, 6231.096491008869, 6242.875142497034, 6255.530835187288, 6288.151099601495, 6308.810735677937, 4947.332110165082, 6142.201513803177, 6185.134081228722, 6197.008478643395, 6209.627422354673, 6229.4393504584805, 6289.591176573508, 6295.464885484602, 6301.810945671454, 6304.495640348853, 5922.763089476545, 5950.702464876652, 5959.28488581373, 6047.758089676364, 6050.42520960508, 6036.245469316335, 6048.516118710405, 6049.758009679739, 6073.544484163617, 6075.3317135115985, 5859.296884373903, 5865.442782935615, 5871.207387002871, 5873.207117039145, 5876.6180198286975, 5205.739713014258, 5361.38344090419, 6053.545984051302, 6057.273127224761, 6062.514998799998, 5848.0, 5860.560457965585, 5871.119332100219, 5877.823665290048, 5884.558658100574], "value": 5633.505150929518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.07424556921603, 476.0906668263615, 518.3287642502959, 526.465637378061, 546.3471151973391, 30504.0, 39590.40614390783, 40351.79824100879, 40707.982300442505, 42761.35857962133, 41660.166796664125, 41979.160416791645, 42016.78991605038, 42034.0, 42047.15905681885, 9332.720018399443, 9509.0, 9510.762230944212, 9517.381370210915, 9538.332316737797, 9392.436453812794, 9485.668001619944, 9502.71491855242, 9502.762430939212, 9518.952405237964, 8710.956445217766, 8837.160469755363, 9436.764080897998, 9520.666776662818, 9584.281178911575, 9430.622775089023, 9501.334906556544, 9506.096920792514, 9506.287028472892, 9518.0, 9379.155875971128, 9452.19656329213, 9467.242620590336, 9470.668526601536, 9472.289578281587, 39536.40695389571, 39619.00952476182, 39897.0, 39928.001799954945, 40016.59983400158, 3262.722668573174, 3293.4236508610898, 3316.519104729825, 3320.9833950830093, 3341.732661387083, 23790.64314035291, 30093.398132037455, 30582.84708576465, 30827.537586936214, 31708.682913170804, 3175.095097897088, 3192.425363434581, 3199.840007999614, 3242.643309235982, 3270.656581058978, 489934.0, 490549.0, 490978.5451072758, 491604.0, 492032.0, 61470.69264653648, 61702.0, 61832.0, 61834.69082654558, 61839.38160618426, 34479.4828077579, 34624.0, 34986.12534686653, 36340.0, 61122.694386527786, 16301.918490407472, 16434.6713065738, 16443.424480143378, 16445.91777041107, 16475.0, 12447.813282800766, 12533.060020498498, 12563.0, 12578.685532861751, 12587.433565489575, 19360.654186524924, 19500.17914725915, 19543.652277997884, 19583.306263399423, 19591.844897061314, 6930.93069069313, 7331.633418329169, 7618.847623047508, 7684.0, 7745.148033716287, 700.6356694518923, 944.7779771753633, 960.7357976556503, 1003.934744241622, 1031.0668844695563, 24176.21973473073, 28336.574951375747, 29677.40645187084, 29855.701442985726, 30538.847305763775, 988.3032462114193, 997.4414327976357, 1021.1830535571513, 1041.1878734587035, 1151.240181480219, 12498.625041248777, 14879.33043013067, 15416.229188540752, 15463.072215667096, 16017.878748487494, 657946.0, 662783.0, 664253.0, 664539.0, 664645.0, 57129.2865963711, 57509.0, 57515.0, 57572.71213643905, 57674.846503069704, 1220.4690959432676, 1266.3541593787236, 1333.2667033131934, 1359.8368195816565, 1377.7520046391746, 30072.84963575168, 31844.68155318417, 32071.071958862933, 32075.198120047186, 33622.83188584042, 56588.0, 57405.0, 57594.13608795955, 59947.0, 60002.09996850138, 604291.0, 611740.0, 612297.0, 612937.0, 613726.0, 43192.56807431886, 44098.11803763843, 45011.0, 45096.77451612721, 45207.0, 44857.55142448534, 46506.06987860292, 48246.758766205945, 48325.75837120792, 49045.75477122592, 39854.80435586937, 40042.39936401015, 41028.1794364117, 41762.791186043876, 41824.95437613962, 32342.514862276614, 38492.42261366026, 39123.80438097791, 39382.409263861635, 39977.80011099926, 39117.80441097776, 39667.603323967545, 40007.79976600707, 40103.3984490227, 40130.79934600425, 29964.700352997064, 32806.34387312288, 34349.65650343464, 34733.826330868185, 34920.0, 25969.22092337233, 26036.34909127287, 26179.69101544953, 26203.4759304809, 26776.0], "value": 20038.77880212174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.03375380166545, 367.346123899459, 375.81021584100023, 379.4062292512218, 404.87646780184986, 14874.702505949874, 15011.849881501195, 15931.920340398296, 20249.39251822444, 20322.187112515483, 19359.51601209967, 19789.0, 20293.492662683402, 20426.182952681876, 20639.484012899647, 5666.121751128571, 5788.105106749047, 5959.910601340962, 5969.686668932828, 5972.134040564138, 4561.0, 5812.6512409255365, 5902.97048514759, 5905.173275741399, 5907.202527658776, 5510.531604813596, 5844.415558444143, 5853.473187413132, 5855.033919403293, 5862.912056319158, 5743.425657434265, 5888.381719919433, 5890.057590785496, 5890.882182356372, 5897.587168898201, 4661.836835710734, 5717.142428635696, 5721.6853073080965, 5876.206712093898, 5919.1713160157615, 12844.614661560168, 13102.868971310356, 13102.868971310356, 13113.213207207598, 23700.288991330286, 2386.5465561543424, 2414.794742446894, 2448.7428819973825, 2779.5691667791525, 2795.161451564543, 9506.334556581041, 9824.32986392315, 10154.441505717183, 10297.691069267934, 10444.582216711322, 1747.5456381340816, 1838.5495553589321, 1972.8323092537153, 2009.2465325502938, 2041.8468614853796, 114656.4267178667, 118752.40623796826, 119460.99999999913, 126180.36909815393, 143513.0, 20070.698939515918, 20342.898285508774, 20821.479463013548, 20966.0, 21358.0, 35154.64845351514, 35301.82349088238, 35797.82101089478, 36066.63933360685, 36092.45861312083, 10713.83929241062, 10745.570177192825, 10814.78370432587, 11026.117910567136, 11034.448277586089, 7517.887231691529, 8797.736067917973, 8957.14907083826, 8991.820163596822, 9196.586153623102, 9468.431894086378, 9538.093881081286, 9584.185344245818, 9607.205153964145, 9653.806923861624, 3211.6627754085484, 3539.6460353964912, 3655.4882316475714, 3661.761985470936, 3666.2686298707636, 775.5734346109617, 816.7223144131024, 877.9912200878038, 966.7051549277538, 967.3035414501547, 19933.60132797365, 20520.692189617166, 20650.89674551648, 20832.0, 20988.895055524623, 903.5572569441058, 915.4598786715869, 920.3189639666598, 936.101342711005, 940.4545363689044, 10164.796704066026, 10281.280310378203, 10726.304962201833, 10870.021698047214, 11085.667429977113, 523236.0, 562930.0, 620632.0, 627954.0, 642404.0, 122504.0, 122598.0, 128422.35788821183, 133253.3337333307, 136321.3183934074, 981.5141504955093, 985.2610542093287, 1017.3285631483186, 1018.8013337399136, 1019.5463018956493, 17871.463856084338, 18450.907745461187, 18744.718829217847, 19069.618607627493, 19156.521086972938, 51410.97178056493, 53091.0, 54699.72650136724, 55488.0, 56262.0, 77605.0, 77881.0, 78697.0, 78937.0, 79391.0, 7721.575313357819, 10640.84038739435, 11693.415329233674, 12028.398580070787, 12400.0, 12466.87533124657, 12978.545750898673, 13556.59330220095, 13766.036377453482, 13828.377723002288, 8278.461899976483, 11497.54009839597, 11703.2978021319, 12013.0, 12028.518859245532, 7248.56508609464, 7802.687892484237, 8073.798155046171, 8106.310963567927, 8240.340772738047, 7883.6846526138315, 7935.444518883623, 7964.044314682275, 8364.581770911309, 8534.231919127309, 12148.817767733315, 12351.567695130627, 12520.937395312965, 12806.295653738762, 12812.55156069533, 10087.193024558164, 11571.6528504145, 11804.0, 11913.642590722293, 12179.330136842558], "value": 10302.745810707635, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.0409305300182, 1377.2975782350998, 1496.3341313115661, 1497.3860717105988, 1821.7085266357378, 7407.111146662402, 7761.6507257173425, 7987.800304992371, 8300.83398332033, 8301.750947471586, 8042.0, 8075.353971682266, 8159.91840081599, 8169.060558035812, 8169.183081691828, 2256.311824893403, 2278.396225000368, 2289.0, 2293.3120063980823, 2293.4610366563843, 2270.421042634134, 2314.143766806276, 2316.258797184893, 2321.141177764221, 2322.988385058081, 2256.9435764105947, 2271.2391348898054, 2289.164454973931, 2289.2445492987276, 2292.541491701658, 2204.239537359608, 2236.3626366485637, 2263.7849404306567, 2266.7959883610556, 2271.0234599122286, 2253.8196944244423, 2279.9316020519245, 2280.85174463661, 2281.4296425893526, 2286.9885650571805, 7956.085050219241, 8132.552709600969, 8212.014558253048, 8227.465214761081, 8227.506349619041, 4931.605471562302, 5119.436861945183, 5126.410462796789, 5126.948730512721, 5134.717590532519, 2910.3306239565018, 2946.484365236075, 3345.381104495676, 3529.400001999666, 3531.8057506837113, 1039.469870366111, 1043.7964596903607, 1062.2935747727752, 1080.5353697909895, 1093.2128867215672, 10583.84124238137, 10648.680539583826, 10721.785564288748, 10762.461876906202, 10848.78302433947, 4182.309918863414, 4213.304804707189, 4343.739375637472, 4351.369051487517, 4362.541933097043, 8269.09040005599, 8283.50298982063, 8303.501789892625, 8310.792230194173, 8311.25198732117, 4947.851564453073, 4952.009598080398, 4953.306537084739, 4953.306537084811, 4955.40535135786, 3887.3391523441082, 4165.0, 4202.2646036943415, 4312.37470566766, 4598.1033698429155, 3567.2865426914723, 3586.336527742376, 3655.250673611903, 3656.213914008501, 3656.6160553142067, 1130.3387518301886, 1142.400239874056, 1144.66804626659, 1152.176194021279, 1156.653004098767, 747.8707152200108, 782.7534326687189, 842.1410161635098, 883.6553744039829, 900.243795212012, 4563.520830312836, 4629.768511574408, 5213.087709650796, 5376.327959005124, 5441.183822426588, 419.9853005144805, 430.9396684464177, 432.35794844655663, 437.63676148796424, 450.85572616762977, 3210.4542227820857, 3647.343478173901, 3661.7986010769664, 3696.1129328961392, 3804.1060350817534, 8623.698170564126, 9520.333576649567, 9890.35712678688, 9972.501374931224, 9992.15066719337, 9608.471534065553, 9862.802743945082, 9874.901250987397, 9914.206863450907, 9937.155341796028, 7334.449916256195, 7519.699212031457, 7608.31525162738, 7627.961860190774, 7629.427792915553, 5168.3797944245425, 5278.2346559749385, 5549.223108764695, 5610.270664813548, 5645.633033852787, 8518.36112291593, 8824.647014119364, 9042.773930651787, 9050.547472626475, 9336.346455748033, 8473.788155296166, 9202.401843880127, 9311.813763724824, 9392.060793920553, 9419.529023548657, 6406.711697973696, 7141.9642901785155, 7448.106227252764, 7677.731279405418, 7747.573883436465, 7588.27911348421, 7754.224577542426, 7851.764447066597, 7852.646630901509, 7855.293023627901, 6998.860022799414, 7056.929430705833, 7095.325944035309, 7097.574145551283, 7113.217546070032, 6849.24658287578, 6899.413549848319, 6941.409980151541, 6983.6857341418745, 7086.539374940612, 6585.5719378240265, 6610.603363798187, 6625.503087268377, 6695.4308883745425, 6705.430038446591, 4610.094243328163, 4642.373279607345, 4665.2302370108555, 4700.55344742249, 4728.952710472851, 4517.367568540472, 4619.467613840449, 4624.9768751156025, 4798.832040878552, 4876.707397556158], "value": 4185.845468730949, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.232468717739, 304.09380047458575, 308.114171756201, 313.6299166982961, 313.6393147879939, 4409.581089796472, 4879.585235254999, 5345.037893179227, 5353.35759708835, 5410.648307859987, 4956.281339205813, 5025.271335656327, 5274.05067087924, 5342.946570534294, 5355.410904800468, 3328.484084966834, 3897.8051097445154, 3916.3955969280396, 3970.4464504268662, 4009.6190861868076, 4064.2887494688457, 4085.12169883475, 4099.323611604082, 4100.4418321037965, 4120.752754834704, 4033.8184781684895, 4037.0, 4063.878083657495, 4072.4705788247634, 4077.9592204078026, 3839.7696138231654, 4020.8994775130564, 4036.5156181258435, 4037.2934736421007, 4095.610916962884, 2851.672057713348, 3439.0, 3487.250241198129, 3493.179102910814, 3517.402041652925, 4519.954800451987, 4796.44840843304, 5210.869728256786, 5237.580993520509, 5266.657667251617, 3959.3467077932105, 4265.87202383929, 4614.884627884297, 4665.323528088442, 4679.532046795327, 5286.867828304285, 5477.589180811416, 5583.408728512395, 5584.342255822443, 5614.971925140389, 3426.697854815154, 3607.531020967284, 3810.2379524095013, 3813.31360355136, 3831.8467261309515, 5184.9740751296, 5401.865608222317, 5793.275840519935, 5802.82591522255, 5856.297244330665, 3617.6382361763613, 3869.5113668145377, 3888.786116763605, 3910.354791459434, 3928.724989250782, 3946.546147193052, 3946.9210615787524, 3955.4462375267485, 3956.7625942443547, 3972.880813575597, 4027.751397066919, 4368.716033457815, 4601.838935637236, 4626.236670949255, 4634.536546345338, 4629.8842528936375, 5413.512783849394, 5625.409332020161, 5649.435056494318, 5659.971700141473, 1101.4162493878257, 1255.5856567332758, 1259.3691169934957, 1266.4870727355435, 1272.2684456437585, 717.6017310392782, 745.9593866556232, 825.4469505431375, 846.3990566697713, 859.5788063848669, 5087.974560127176, 5288.788448462095, 5320.0365727533235, 5385.7576409061685, 5396.514313711785, 4899.559039686445, 5592.937341905025, 5849.298084229835, 5868.67722275279, 5891.114843250119, 5130.30740849988, 5238.528532432099, 5292.686460534652, 5303.7348132593215, 5314.61819926814, 2843.7582805461766, 2851.70057144001, 2854.160110689835, 2884.5528943013446, 2919.6715494449973, 5332.253889247041, 5547.667139971614, 5575.52608028314, 5598.188262701886, 5713.315081633021, 4878.268214783771, 4989.355641510006, 5070.670406423571, 5515.972420137874, 5631.267935168484, 4922.163232250455, 5453.590980676386, 5699.689071513492, 5703.686297253608, 5769.249997500298, 4655.883602909955, 4669.968563674813, 5119.078565858182, 5457.918131227955, 5577.27495425592, 4394.560543945579, 5402.378726446509, 5425.565954723692, 5445.83662490126, 5518.86202844916, 5090.541851233259, 5250.501202385767, 5302.257683924331, 5647.322321321468, 5768.538516918553, 3054.823892801243, 3086.6758990305693, 3104.7826652135036, 3659.2864391443413, 3674.7060235181657, 4691.554302341272, 4980.676256043345, 5099.419180054195, 5356.035913535601, 5591.804286849941, 5115.437301896863, 5296.867578310573, 5301.674581354661, 5308.654937429209, 5324.988252232063, 4705.0, 4986.351774269467, 5276.841694749163, 5313.867153321198, 5340.652857564246, 4430.889227769331, 4803.567678908776, 5074.802874189237, 5098.719570423662, 5115.189184057901, 4578.0, 4578.855286178456, 5298.602604804578, 5443.673379597237, 5528.889422211614, 4382.671299652603, 5477.287952566141, 5521.282233309644, 5527.861803454945, 5535.640183388228], "value": 3981.3268979648333, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.0472315381894, 700.0409439068477, 765.2385876053328, 774.4307933668753, 787.5353541410568, 13793.310334483273, 14223.502177423792, 14236.857631423683, 15130.54608361748, 15959.12224827634, 14354.569362919103, 14616.561503154922, 14886.25568721565, 14923.552293431187, 14985.625359366022, 795.4789612803609, 826.801567623769, 828.2131974624131, 829.1335554345724, 829.2619568583965, 648.244794814041, 665.5939876675225, 795.7453614843253, 818.0, 827.1645637905729, 870.251583638074, 870.3602851903846, 870.7039606533777, 871.4422769427572, 871.8997315308726, 862.8231212601402, 863.7020228021322, 863.7970077031893, 864.2308345572445, 866.6490071521044, 436.301916932908, 500.17970528333564, 514.8558403646985, 642.1170890026214, 642.7975187815823, 12128.636140915787, 12129.09031822608, 12171.391430428532, 13762.518311859138, 14303.856961430356, 13207.40566674502, 13412.26232557209, 13584.93207533956, 13590.25253611051, 13620.863791362059, 10976.506057227443, 11283.774324513464, 11448.0, 11458.598949036743, 11525.36287889748, 3501.6848483636595, 3783.3946568549177, 3787.507624008889, 3802.315583195024, 3825.598312177237, 74189.0, 74864.62567687126, 91630.0, 93942.0, 95032.5745113817, 10310.123639490577, 10444.63443779464, 10458.581656733797, 10496.160307175407, 10535.262531622866, 20013.699794503093, 20088.39734807958, 20308.18767249323, 20410.591788164154, 20423.591528169352, 7296.233895440902, 7471.140818805907, 7491.887621685679, 7650.617469126521, 7732.497387669896, 4132.2561938850695, 4147.129102888367, 4184.665226781911, 4314.985978295098, 4422.977885110554, 3373.5445714828184, 3478.773879697862, 3484.285721427101, 3495.545579074704, 3507.4037413639744, 614.4684847606788, 615.2094558492316, 624.341319907502, 636.2015670333701, 639.6865535887382, 565.7030059218943, 586.7652938824505, 608.613530408194, 665.128681427322, 672.7291669767427, 8552.187542183483, 8892.48746745032, 9239.168474837297, 9433.386829856037, 9531.904680953101, 563.5913962377243, 568.6957477749385, 640.6892657061414, 655.3250152342999, 665.1618960110296, 3596.0, 3773.566039905446, 3844.0, 3975.383815508601, 4180.728252663567, 80064.5996770024, 82360.58819705863, 103998.96001040043, 104859.0, 106300.46849765701, 69305.3069469309, 72799.0, 74607.25392746112, 89567.55216223878, 106544.0, 89351.55324223338, 93008.0, 93280.53359733293, 93540.53229733808, 93818.59272110923, 7780.299773020455, 8003.19968003195, 8004.959355283776, 8072.515649061075, 8370.330373570221, 53427.0, 54868.72565637146, 55687.44312556823, 56003.159952601556, 57471.71264143653, 40538.59461405466, 54035.45964540462, 63058.684706576176, 72396.27603723896, 83566.58216708878, 10862.0, 10867.945660271964, 10965.725856853642, 11201.979450285231, 11483.0, 11821.645350639494, 12031.398430078287, 12199.390030498616, 12339.383030848243, 12581.119321647397, 9699.418034917928, 10241.641542545975, 11897.524099035942, 11914.404279785802, 12137.635870923887, 11608.24546404481, 11773.646790596296, 11863.228890122115, 11877.821832672344, 12272.0, 11336.376499292619, 11886.465109069939, 12343.629691109281, 12756.808647870104, 13335.133216340906, 10452.216083793897, 10729.785404291715, 10751.892481075402, 10767.084797792275, 11372.374519401512, 10904.945475272572, 11020.135225499474, 11201.495932683212, 11337.922897324741, 11478.82781758291], "value": 7008.328778390443, "unit": "score"}], "name": "dromaeo_css", "value": 7169.9058157272675, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 112.0, 111.0, 104.0, 113.0, 113.0, 113.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 104.0, 147.0, 113.0, 122.0, 139.0, 140.0, 143.0, 110.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 187.0, 182.0, 183.0, 186.0, 184.0, 185.0, 182.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 88.0, 60.0, 59.0, 58.0, 57.0, 82.0, 57.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 94.0, 70.0, 67.0, 69.0, 89.0, 67.0, 68.0, 91.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 89.0, 84.0, 90.0, 85.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 93.0, 101.0, 142.0, 108.0, 94.0, 94.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 82.0, 82.0, 82.0, 83.0, 85.0, 86.0, 84.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 53.0, 55.0, 58.0, 56.0, 56.0, 56.0, 57.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 75.0, 78.0, 72.0, 71.0, 71.0, 73.0, 72.0, 73.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 84.0, 88.0, 83.0, 86.0, 81.0, 82.0, 89.0, 92.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 160.0, 160.0, 137.0, 148.0, 137.0, 164.0, 211.0, 177.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 167.0, 166.0, 165.0, 168.0, 205.0, 160.0, 213.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 71.0, 63.0, 58.0, 64.0, 86.0, 84.0, 87.0, 77.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1456.6000000000001, "alertThreshold": 2.0}]} 22:53:12 INFO - Return code: 0 22:53:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:53:12 ERROR - # TBPL SUCCESS # 22:53:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:53:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:53:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:53:12 INFO - 2016-11-14 22:53:12.834 screenresolution[2196:26722] starting screenresolution argv=screenresolution get 22:53:12 INFO - 2016-11-14 22:53:12.837 screenresolution[2196:26722] Display 0: 1600x1200x32@60 22:53:12 INFO - 2016-11-14 22:53:12.843 screenresolution[2197:26725] starting screenresolution argv=screenresolution list 22:53:12 INFO - Available Modes on Display 0 22:53:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:53:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:53:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:53:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:53:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:53:13 INFO - Intel Iris: 22:53:13 INFO - Chipset Model: Intel Iris 22:53:13 INFO - Type: GPU 22:53:13 INFO - Bus: Built-In 22:53:13 INFO - VRAM (Dynamic, Max): 1536 MB 22:53:13 INFO - Vendor: Intel (0x8086) 22:53:13 INFO - Device ID: 0x0a2e 22:53:13 INFO - Revision ID: 0x0009 22:53:13 INFO - Displays: 22:53:13 INFO - Display: 22:53:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:53:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:53:13 INFO - Display Serial Number: 1600x1200 60 22:53:13 INFO - Main Display: Yes 22:53:13 INFO - Mirror: Off 22:53:13 INFO - Online: Yes 22:53:13 INFO - Rotation: Supported 22:53:13 INFO - Return code: 0 22:53:13 INFO - Running post-action listener: _resource_record_post_action 22:53:13 INFO - [mozharness: 2016-11-15 06:53:13.080336Z] Finished run-tests step (success) 22:53:13 INFO - Running post-run listener: _resource_record_post_run 22:53:13 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 610304; Write bytes: 650869760; Read time: 6; Write time: 1826 22:53:13 INFO - TinderboxPrint: CPU usage
53.5% 22:53:13 INFO - TinderboxPrint: I/O read bytes / time
610,304 / 6 22:53:13 INFO - TinderboxPrint: I/O write bytes / time
650,869,760 / 1,826 22:53:13 INFO - TinderboxPrint: CPU idle
2,249.7 (46.5%) 22:53:13 INFO - TinderboxPrint: CPU system
974.8 (20.1%) 22:53:13 INFO - TinderboxPrint: CPU user
1,617.2 (33.4%) 22:53:13 INFO - TinderboxPrint: Swap in / out
353,271,808 / 0 22:53:13 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348234240; Write bytes: 347222528; Read time: 27200; Write time: 648 22:53:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 610304; Write bytes: 292686336; Read time: 6; Write time: 1146 22:53:13 INFO - Running post-run listener: _upload_blobber_files 22:53:13 INFO - Blob upload gear active. 22:53:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:53:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:53:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:53:14 INFO - (blobuploader) - INFO - Open directory for files ... 22:53:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:53:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:53:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:53:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:53:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:53:14 INFO - (blobuploader) - INFO - Done attempting. 22:53:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:53:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:53:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:53:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:53:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:53:15 INFO - (blobuploader) - INFO - Done attempting. 22:53:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:53:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:53:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:53:17 INFO - (blobuploader) - INFO - Done attempting. 22:53:17 INFO - (blobuploader) - INFO - Iteration through files over. 22:53:18 INFO - Return code: 0 22:53:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:53:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:53:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95fa55de5027c79c563b03fcab7ffb372380525062a24704f476d08123c9f649ea2942d0be35e1d1eab8a4804c51fc918a41343aedf70f1afe55345fdb519ebe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8057b3aad96370abff9e1be0026a2e728b930e3145c25ffb85861554077eca8647fb61c182770bfb0ce8656b0400a8738852fba1c4399253a88aafe3739007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14a1c34f75b72fe78aa9e7afdfe1090818b9c7a2b774e0759cc03c10fa1437232ce1895fc82efb6bf5c72081bce8dde8e456df531dbe6a81186eeff5c69fdf19"} 22:53:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:53:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:53:18 INFO - Contents: 22:53:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95fa55de5027c79c563b03fcab7ffb372380525062a24704f476d08123c9f649ea2942d0be35e1d1eab8a4804c51fc918a41343aedf70f1afe55345fdb519ebe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8057b3aad96370abff9e1be0026a2e728b930e3145c25ffb85861554077eca8647fb61c182770bfb0ce8656b0400a8738852fba1c4399253a88aafe3739007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14a1c34f75b72fe78aa9e7afdfe1090818b9c7a2b774e0759cc03c10fa1437232ce1895fc82efb6bf5c72081bce8dde8e456df531dbe6a81186eeff5c69fdf19"} 22:53:18 INFO - Running post-run listener: copy_logs_to_upload_dir 22:53:18 INFO - Copying logs to upload dir... 22:53:18 INFO - mkdir: /builds/slave/test/build/upload/logs 22:53:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.631604 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-11-14 22:53:18.066559) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:18.069796) ========= 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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95fa55de5027c79c563b03fcab7ffb372380525062a24704f476d08123c9f649ea2942d0be35e1d1eab8a4804c51fc918a41343aedf70f1afe55345fdb519ebe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8057b3aad96370abff9e1be0026a2e728b930e3145c25ffb85861554077eca8647fb61c182770bfb0ce8656b0400a8738852fba1c4399253a88aafe3739007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14a1c34f75b72fe78aa9e7afdfe1090818b9c7a2b774e0759cc03c10fa1437232ce1895fc82efb6bf5c72081bce8dde8e456df531dbe6a81186eeff5c69fdf19"} build_url:https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/dKN_k_ezTzyJtu93rBAMhQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95fa55de5027c79c563b03fcab7ffb372380525062a24704f476d08123c9f649ea2942d0be35e1d1eab8a4804c51fc918a41343aedf70f1afe55345fdb519ebe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8057b3aad96370abff9e1be0026a2e728b930e3145c25ffb85861554077eca8647fb61c182770bfb0ce8656b0400a8738852fba1c4399253a88aafe3739007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14a1c34f75b72fe78aa9e7afdfe1090818b9c7a2b774e0759cc03c10fa1437232ce1895fc82efb6bf5c72081bce8dde8e456df531dbe6a81186eeff5c69fdf19"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:18.103542) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:18.103864) ========= 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.ZbIBZtYC3x/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.4lbtWtEubk/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005194 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:53:18.152396) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:53:18.152732) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:53:18.153057) ========= ========= Total master_lag: 1.01 =========